[12:34:07.114] INFO: *** Welcome to pxar ***
[12:34:07.114] INFO: *** Today: 2016/08/30
[12:34:08.032] INFO: *** Version: v1.9.0-818-g96727
[12:34:08.032] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//dacParameters35_C15.dat
[12:34:08.092] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:34:08.092] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//defaultMaskFile.dat
[12:34:08.092] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C15.dat
[12:34:08.205] INFO: clk: 4
[12:34:08.205] INFO: ctr: 4
[12:34:08.205] INFO: sda: 19
[12:34:08.205] INFO: tin: 9
[12:34:08.205] INFO: level: 15
[12:34:08.205] INFO: triggerdelay: 0
[12:34:08.205] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:34:08.206] INFO: Log level: INFO
[12:34:08.226] QUIET: Connection to board DTB_WREKRL opened.
[12:34:08.229] 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:
------------------------------------------------------
[12:34:08.232] INFO: RPC call hashes of host and DTB match: 398089610
[12:34:09.769] INFO: DUT info:
[12:34:09.769] INFO: The DUT currently contains the following objects:
[12:34:09.769] INFO: 2 TBM Cores tbm08c (2 ON)
[12:34:09.769] INFO: TBM Core alpha (0): 7 registers set
[12:34:09.769] INFO: TBM Core beta (1): 7 registers set
[12:34:09.769] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:34:09.769] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:09.769] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:09.769] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:09.769] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:09.769] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:09.769] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:09.769] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:09.769] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:09.769] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:09.769] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:09.769] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:09.769] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:09.769] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:09.769] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:09.769] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:09.769] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:10.200] INFO: enter 'restricted' command line mode
[12:34:10.200] INFO: enter test to run
[12:34:34.095] INFO: test: PixelAlive no parameter change
[12:34:34.095] INFO: running: pixelalive
[12:34:34.170] INFO: ----------------------------------------------------------------------
[12:34:34.170] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:34:34.170] INFO: ----------------------------------------------------------------------
[12:34:34.485] INFO: Expecting 41600 events.
[12:34:38.823] INFO: 41600 events read in total (3620ms).
[12:34:38.992] INFO: Test took 4818ms.
[12:34:38.004] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:39.516] INFO: PixTestAlive::aliveTest() done
[12:34:39.516] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 6 0 0 0 0 0
[12:34:39.694] INFO: enter test to run
[12:35:39.038] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:35:39.039] INFO: running: highrate
[12:35:39.057] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:35:39.302] INFO: ----------------------------------------------------------------------
[12:35:39.302] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:35:39.302] INFO: ----------------------------------------------------------------------
[12:35:39.302] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:35:39.302] INFO: edge/corner pixel THR is adjusted
[12:35:39.302] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:35:40.262] INFO: Collecting data for 5 seconds...
[12:35:45.279] INFO: Done with hot pixel readout
[12:35:56.756] INFO: PixTest:: pg_setup set to default.


[12:35:56.757] INFO: 11 hot pixels found in step 0
[12:35:57.768] INFO: Collecting data for 5 seconds...
[12:36:02.786] INFO: Done with hot pixel readout
[12:36:14.227] INFO: PixTest:: pg_setup set to default.
[12:36:14.228] INFO: 5 hot pixels found in step 1
[12:36:15.214] INFO: Collecting data for 5 seconds...
[12:36:20.232] INFO: Done with hot pixel readout
[12:36:31.669] INFO: PixTest:: pg_setup set to default.
[12:36:31.670] INFO: 5 hot pixels found in step 2
[12:36:32.655] INFO: Collecting data for 5 seconds...
[12:36:37.672] INFO: Done with hot pixel readout
[12:36:49.078] INFO: PixTest:: pg_setup set to default.
[12:36:49.079] INFO: 6 hot pixels found in step 3
[12:36:50.066] INFO: Collecting data for 5 seconds...
[12:36:55.084] INFO: Done with hot pixel readout
[12:37:06.515] INFO: PixTest:: pg_setup set to default.
[12:37:06.516] INFO: 6 hot pixels found in step 4
[12:37:07.503] INFO: Collecting data for 5 seconds...
[12:37:12.519] INFO: Done with hot pixel readout
[12:37:23.869] INFO: PixTest:: pg_setup set to default.
[12:37:23.870] INFO: 6 hot pixels found in step 5
[12:37:24.857] INFO: Collecting data for 5 seconds...
[12:37:29.875] INFO: Done with hot pixel readout
[12:37:41.304] INFO: PixTest:: pg_setup set to default.
[12:37:41.305] INFO: 7 hot pixels found in step 6
[12:37:42.292] INFO: Collecting data for 5 seconds...
[12:37:47.312] INFO: Done with hot pixel readout
[12:37:58.842] INFO: PixTest:: pg_setup set to default.
[12:37:58.843] INFO: 5 hot pixels found in step 7
[12:37:59.829] INFO: Collecting data for 5 seconds...
[12:38:04.850] INFO: Done with hot pixel readout
[12:38:16.196] INFO: PixTest:: pg_setup set to default.
[12:38:16.197] INFO: 5 hot pixels found in step 8
[12:38:17.184] INFO: Collecting data for 5 seconds...
[12:38:22.204] INFO: Done with hot pixel readout
[12:38:33.475] INFO: PixTest:: pg_setup set to default.

[12:38:33.475] INFO: 5 hot pixels found in step 9
[12:38:34.461] INFO: Collecting data for 5 seconds...
[12:38:39.481] INFO: Done with hot pixel readout
[12:38:50.006] INFO: PixTest:: pg_setup set to default.
[12:38:51.007] INFO: 6 hot pixels found in step 10
[12:38:51.993] INFO: Collecting data for 5 seconds...
[12:38:57.011] INFO: Done with hot pixel readout
[12:39:08.347] INFO: PixTest:: pg_setup set to default.
[12:39:08.348] INFO: 4 hot pixels found in step 11
[12:39:09.334] INFO: Collecting data for 5 seconds...
[12:39:14.353] INFO: Done with hot pixel readout
[12:39:25.609] INFO: PixTest:: pg_setup set to default.
[12:39:25.610] INFO: 5 hot pixels found in step 12
[12:39:26.596] INFO: Collecting data for 5 seconds...
[12:39:31.614] INFO: Done with hot pixel readout
[12:39:42.966] INFO: PixTest:: pg_setup set to default.
[12:39:42.967] INFO: 6 hot pixels found in step 13
[12:39:43.953] INFO: Collecting data for 5 seconds...
[12:39:48.971] INFO: Done with hot pixel readout
[12:40:00.175] INFO: PixTest:: pg_setup set to default.
[12:40:00.176] INFO: 5 hot pixels found in step 14
[12:40:00.207] INFO: 5 hot pixels could not be trimmed and have been masked.
[12:40:00.219] INFO: PixTest::trimHotPixels() done
[12:40:00.222] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C0.dat
[12:40:00.230] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C1.dat
[12:40:00.236] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C2.dat
[12:40:00.241] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C3.dat
[12:40:00.247] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C4.dat
[12:40:00.252] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C5.dat
[12:40:00.257] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C6.dat
[12:40:00.263] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C7.dat
[12:40:00.268] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C8.dat
[12:40:00.273] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C9.dat
[12:40:00.279] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C10.dat
[12:40:00.284] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C11.dat
[12:40:00.289] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C12.dat
[12:40:00.295] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C13.dat
[12:40:00.300] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C14.dat
[12:40:00.305] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C15.dat
[12:40:00.311] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//defaultMaskFile.dat
[12:40:00.321] INFO: enter test to run
[12:43:23.331] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:43:23.331] INFO: running: highrate
[12:43:23.336] INFO: ----------------------------------------------------------------------
[12:43:23.336] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:43:23.336] INFO: ----------------------------------------------------------------------
[12:43:23.336] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:43:23.336] INFO: edge/corner pixel THR is adjusted
[12:43:23.336] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:43:24.298] INFO: Collecting data for 1 seconds...
[12:43:25.301] INFO: Done with hot pixel readout
[12:43:29.109] INFO: PixTest:: pg_setup set to default.
[12:43:29.110] INFO: 0 hot pixels found in step 0
[12:43:29.115] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:43:29.212] INFO: PixTest::trimHotPixels() done
[12:43:29.212] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C0.dat
[12:43:29.221] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C1.dat
[12:43:29.227] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C2.dat
[12:43:29.232] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C3.dat
[12:43:29.238] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C4.dat
[12:43:29.243] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C5.dat
[12:43:29.248] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C6.dat
[12:43:29.253] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C7.dat
[12:43:29.259] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C8.dat
[12:43:29.264] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C9.dat
[12:43:29.269] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C10.dat
[12:43:29.274] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C11.dat
[12:43:29.280] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C12.dat
[12:43:29.285] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C13.dat
[12:43:29.290] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C14.dat
[12:43:29.295] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//trimParameters35_C15.dat
[12:43:29.301] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-A-11_FPIXTest-17C-FNAL-160823-1253-300V_2016-08-23_12h53m_1471974812/000_FPIXTest_p17//defaultMaskFile.dat
[12:43:29.311] INFO: enter test to run
[12:43:53.323] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:43:53.323] INFO: running: xray
[12:43:53.324] INFO: ----------------------------------------------------------------------
[12:43:53.324] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:43:53.324] INFO: ----------------------------------------------------------------------
[12:43:54.310] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:44:06.047] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:44:35.390] INFO: Resuming triggers.
[12:44:47.131] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:45:16.495] INFO: Resuming triggers.
[12:45:28.233] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[12:45:57.477] INFO: Resuming triggers.
[12:46:09.216] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[12:46:38.545] INFO: Resuming triggers.
[12:46:50.285] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[12:47:19.618] INFO: Resuming triggers.
[12:47:31.356] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[12:48:00.561] INFO: Resuming triggers.
[12:48:12.302] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[12:48:41.556] INFO: Resuming triggers.
[12:48:53.296] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[12:49:22.456] INFO: Resuming triggers.
[12:49:28.882] INFO: data taking finished, elapsed time: 100 seconds.
[12:49:45.182] INFO: PixTest:: pg_setup set to default.
[12:49:45.186] INFO: PixTestXray::doPhRun() done
[12:49:45.322] INFO: enter test to run
[12:52:02.136] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:52:02.136] INFO: running: xray
[12:52:02.137] INFO: ----------------------------------------------------------------------
[12:52:02.137] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:52:02.137] INFO: ----------------------------------------------------------------------
[12:52:03.103] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:52:10.240] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[12:52:40.417] INFO: Resuming triggers.
[12:52:47.556] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[12:53:19.250] INFO: Resuming triggers.
[12:53:26.389] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[12:53:58.010] INFO: Resuming triggers.
[12:54:05.148] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[12:54:36.055] INFO: Resuming triggers.
[12:54:43.196] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[12:55:13.431] INFO: Resuming triggers.
[12:55:20.571] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[12:55:50.903] INFO: Resuming triggers.
[12:55:58.048] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[12:56:28.344] INFO: Resuming triggers.
[12:56:35.483] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[12:57:05.782] INFO: Resuming triggers.
[12:57:12.922] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[12:57:43.139] INFO: Resuming triggers.
[12:57:50.278] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[12:58:20.422] INFO: Resuming triggers.
[12:58:27.569] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[12:58:57.771] INFO: Resuming triggers.
[12:59:04.912] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[12:59:35.163] INFO: Resuming triggers.
[12:59:42.304] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[13:00:12.451] INFO: Resuming triggers.
[13:00:19.597] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[13:00:49.788] INFO: Resuming triggers.
[13:00:50.173] INFO: data taking finished, elapsed time: 100 seconds.
[13:00:52.184] INFO: PixTest:: pg_setup set to default.
[13:00:52.188] INFO: PixTestXray::doPhRun() done
[13:00:52.340] INFO: enter test to run
[13:03:10.091] INFO: test: HighRate no parameter change
[13:03:10.091] INFO: running: highrate
[13:03:10.107] INFO: ----------------------------------------------------------------------
[13:03:10.107] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:03:10.107] INFO: ----------------------------------------------------------------------
[13:03:10.262] INFO: Expecting 768 events.
[13:03:11.396] INFO: 768 events read in total (419ms).
[13:03:11.396] INFO: Test took 1269ms.
[13:03:12.199] INFO: Expecting 41600 events.
[13:03:15.235] INFO: 41600 events read in total (2510ms).
[13:03:15.236] INFO: Test took 3814ms.
[13:03:15.267] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:15.997] INFO: Expecting 41600 events.
[13:03:19.232] INFO: 41600 events read in total (2708ms).
[13:03:19.233] INFO: Test took 3950ms.
[13:03:19.265] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:19.992] INFO: Expecting 41600 events.
[13:03:23.122] INFO: 41600 events read in total (2605ms).
[13:03:23.123] INFO: Test took 3840ms.
[13:03:23.156] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:23.884] INFO: Expecting 41600 events.
[13:03:27.045] INFO: 41600 events read in total (2634ms).
[13:03:27.046] INFO: Test took 3874ms.
[13:03:27.078] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:27.803] INFO: Expecting 41600 events.
[13:03:30.006] INFO: 41600 events read in total (2677ms).
[13:03:31.007] INFO: Test took 3913ms.
[13:03:31.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:31.764] INFO: Expecting 41600 events.
[13:03:35.060] INFO: 41600 events read in total (2769ms).
[13:03:35.061] INFO: Test took 4004ms.
[13:03:35.093] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:35.808] INFO: Expecting 41600 events.
[13:03:38.977] INFO: 41600 events read in total (2642ms).
[13:03:38.978] INFO: Test took 3868ms.
[13:03:39.010] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:39.738] INFO: Expecting 41600 events.
[13:03:42.964] INFO: 41600 events read in total (2699ms).
[13:03:42.965] INFO: Test took 3937ms.
[13:03:42.999] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:43.720] INFO: Expecting 41600 events.
[13:03:46.916] INFO: 41600 events read in total (2670ms).
[13:03:46.917] INFO: Test took 3899ms.
[13:03:46.949] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:47.671] INFO: Expecting 41600 events.
[13:03:50.924] INFO: 41600 events read in total (2726ms).
[13:03:50.925] INFO: Test took 3959ms.
[13:03:50.957] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:51.682] INFO: Expecting 41600 events.
[13:03:54.911] INFO: 41600 events read in total (2702ms).
[13:03:54.912] INFO: Test took 3938ms.
[13:03:54.944] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:55.672] INFO: Expecting 41600 events.
[13:03:58.869] INFO: 41600 events read in total (2671ms).
[13:03:58.869] INFO: Test took 3908ms.
[13:03:58.902] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:59.625] INFO: Expecting 41600 events.
[13:04:02.818] INFO: 41600 events read in total (2667ms).
[13:04:02.818] INFO: Test took 3899ms.
[13:04:02.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:03.577] INFO: Expecting 41600 events.
[13:04:06.838] INFO: 41600 events read in total (2735ms).
[13:04:06.839] INFO: Test took 3972ms.
[13:04:06.871] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:07.582] INFO: Expecting 41600 events.
[13:04:10.775] INFO: 41600 events read in total (2667ms).
[13:04:10.776] INFO: Test took 3889ms.
[13:04:10.808] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:11.536] INFO: Expecting 41600 events.
[13:04:14.738] INFO: 41600 events read in total (2675ms).
[13:04:14.739] INFO: Test took 3915ms.
[13:04:14.771] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:15.500] INFO: Expecting 41600 events.
[13:04:18.655] INFO: 41600 events read in total (2629ms).
[13:04:18.656] INFO: Test took 3868ms.
[13:04:18.688] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:19.410] INFO: Expecting 41600 events.
[13:04:22.664] INFO: 41600 events read in total (2727ms).
[13:04:22.665] INFO: Test took 3960ms.
[13:04:22.702] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:23.407] INFO: Expecting 41600 events.
[13:04:26.601] INFO: 41600 events read in total (2667ms).
[13:04:26.602] INFO: Test took 3883ms.
[13:04:26.635] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:27.361] INFO: Expecting 41600 events.
[13:04:30.375] INFO: 41600 events read in total (2487ms).
[13:04:30.376] INFO: Test took 3723ms.
[13:04:30.408] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:30.829] INFO: enter test to run
[13:05:13.266] INFO: test: HighRate no parameter change
[13:05:13.267] INFO: running: highrate
[13:05:13.268] INFO: ----------------------------------------------------------------------
[13:05:13.268] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:05:13.268] INFO: ----------------------------------------------------------------------
[13:05:13.887] INFO: Expecting 208000 events.
[13:05:26.037] INFO: 208000 events read in total (11623ms).
[13:05:26.039] INFO: Test took 12762ms.
[13:05:26.173] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:26.423] INFO: number of dead pixels (per ROC): 0 161 0 0 0 0 0 0 0 0 5 0 0 0 0 0
[13:05:26.423] INFO: number of red-efficiency pixels: 93 207 66 135 131 137 127 79 78 95 129 87 86 59 16 29
[13:05:26.423] INFO: number of X-ray hits detected: 70522 38773 62529 97742 101574 104476 100105 69829 65341 89002 88568 73414 76064 47160 19634 24509
[13:05:26.423] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:05:26.423] INFO: number of Vcal hits detected: 207905 199904 207931 207862 207866 207861 207855 207918 207920 207903 207623 207910 207912 207940 207984 207971
[13:05:26.423] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0
[13:05:26.423] INFO: Vcal hit overall efficiency (%): 100.0 96.1 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 99.8 100.0 100.0 100.0 100.0 100.0
[13:05:26.423] INFO: X-ray hit rate [MHz/cm2]: 20.7 11.4 18.3 28.6 29.8 30.6 29.3 20.5 19.2 26.1 26.0 21.5 22.3 13.8 5.8 7.2
[13:05:26.423] INFO: PixTestHighRate::doXPixelAlive() done
[13:05:26.468] INFO: PixTest:: pg_setup set to default.
[13:05:26.479] INFO: enter test to run
[13:05:51.770] INFO: test: HighRate no parameter change
[13:05:51.770] INFO: running: highrate
[13:05:51.771] INFO: ----------------------------------------------------------------------
[13:05:51.771] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:05:51.771] INFO: ----------------------------------------------------------------------
[13:05:52.389] INFO: Expecting 208000 events.
[13:06:05.923] INFO: 208000 events read in total (13008ms).
[13:06:05.928] INFO: Test took 14146ms.
[13:06:06.202] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:06.498] INFO: number of dead pixels (per ROC): 0 161 0 0 0 0 0 0 0 0 5 0 0 0 0 0
[13:06:06.498] INFO: number of red-efficiency pixels: 253 274 232 341 433 476 352 267 189 299 371 261 228 155 43 55
[13:06:06.498] INFO: number of X-ray hits detected: 144038 80562 128835 199134 206924 214288 205609 143517 134474 182124 181021 149548 155480 96040 39871 49424
[13:06:06.499] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:06:06.499] INFO: number of Vcal hits detected: 207722 199833 207754 207634 207534 207483 207615 207716 207805 207677 207360 207720 207761 207841 207956 207945
[13:06:06.499] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[13:06:06.499] INFO: Vcal hit overall efficiency (%): 99.9 96.1 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.7 99.9 99.9 99.9 100.0 100.0
[13:06:06.499] INFO: X-ray hit rate [MHz/cm2]: 42.2 23.6 37.8 58.4 60.7 62.8 60.3 42.1 39.4 53.4 53.1 43.8 45.6 28.2 11.7 14.5
[13:06:06.499] INFO: PixTestHighRate::doXPixelAlive() done
[13:06:06.545] INFO: PixTest:: pg_setup set to default.
[13:06:06.560] INFO: enter test to run
[13:06:39.018] INFO: test: HighRate no parameter change
[13:06:39.018] INFO: running: highrate
[13:06:39.019] INFO: ----------------------------------------------------------------------
[13:06:39.019] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:06:39.019] INFO: ----------------------------------------------------------------------
[13:06:39.645] INFO: Expecting 208000 events.
[13:06:54.746] INFO: 208000 events read in total (14574ms).
[13:06:54.753] INFO: Test took 15722ms.
[13:06:55.160] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:55.500] INFO: number of dead pixels (per ROC): 0 161 0 0 0 0 0 0 0 0 5 0 0 0 0 0
[13:06:55.500] INFO: number of red-efficiency pixels: 516 400 520 794 1069 1121 786 595 453 732 778 506 612 335 64 92
[13:06:55.500] INFO: number of X-ray hits detected: 219325 122195 196912 303392 316369 326960 311625 219515 204360 278085 275096 229935 237826 147325 61596 75830
[13:06:55.500] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:06:55.500] INFO: number of Vcal hits detected: 207366 199686 207323 207063 206664 206570 207053 207290 207505 207177 206844 207433 207332 207620 207932 207907
[13:06:55.500] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.7 99.6 99.4 99.4 99.6 99.7 99.8 99.6 99.6 99.8 99.7 99.8 100.0 100.0
[13:06:55.500] INFO: Vcal hit overall efficiency (%): 99.7 96.0 99.7 99.5 99.4 99.3 99.5 99.7 99.8 99.6 99.4 99.7 99.7 99.8 100.0 100.0
[13:06:55.500] INFO: X-ray hit rate [MHz/cm2]: 64.3 35.8 57.7 88.9 92.7 95.8 91.3 64.3 59.9 81.5 80.6 67.4 69.7 43.2 18.1 22.2
[13:06:55.500] INFO: PixTestHighRate::doXPixelAlive() done
[13:06:55.547] INFO: PixTest:: pg_setup set to default.
[13:06:55.565] INFO: enter test to run
[13:07:07.073] INFO: test: exit no parameter change
[13:07:07.391] QUIET: Connection to board 33 closed.
[13:07:07.402] INFO: pXar: this is the end, my friend