[12:52:40.564] INFO: *** Welcome to pxar ***
[12:52:40.564] INFO: *** Today: 2016/07/06
[12:52:41.567] INFO: *** Version: v1.9.0-814-g7497
[12:52:41.567] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//dacParameters35_C15.dat
[12:52:41.641] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:52:41.641] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//defaultMaskFile.dat
[12:52:41.653] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C15.dat
[12:52:41.816] INFO: clk: 4
[12:52:41.816] INFO: ctr: 4
[12:52:41.816] INFO: sda: 19
[12:52:41.816] INFO: tin: 9
[12:52:41.816] INFO: level: 15
[12:52:41.816] INFO: triggerdelay: 0
[12:52:41.816] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:52:41.816] INFO: Log level: INFO
[12:52:41.832] QUIET: Connection to board DTB_WREKRL opened.
[12:52:41.835] 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:52:41.838] INFO: RPC call hashes of host and DTB match: 398089610
[12:52:43.391] INFO: DUT info:
[12:52:43.391] INFO: The DUT currently contains the following objects:
[12:52:43.391] INFO: 2 TBM Cores tbm08c (2 ON)
[12:52:43.391] INFO: TBM Core alpha (0): 7 registers set
[12:52:43.391] INFO: TBM Core beta (1): 7 registers set
[12:52:43.391] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:52:43.391] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.391] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.391] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.391] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.391] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.391] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.391] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.391] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.391] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.391] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.391] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.391] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.391] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.391] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.391] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.391] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:52:43.841] INFO: enter 'restricted' command line mode
[12:52:43.841] INFO: enter test to run
[12:53:06.544] INFO: test: PixelAlive no parameter change
[12:53:06.545] INFO: running: pixelalive
[12:53:06.610] INFO: ----------------------------------------------------------------------
[12:53:06.610] 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:53:06.610] INFO: ----------------------------------------------------------------------
[12:53:06.933] INFO: Expecting 41600 events.
[12:53:11.271] INFO: 41600 events read in total (3620ms).
[12:53:11.437] INFO: Test took 4824ms.
[12:53:11.450] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:53:12.012] INFO: PixTestAlive::aliveTest() done
[12:53:12.012] INFO: number of dead pixels (per ROC): 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:53:12.185] INFO: enter test to run
[12:54:05.512] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:54:05.512] INFO: running: highrate
[12:54:05.522] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:54:05.805] INFO: ----------------------------------------------------------------------
[12:54:05.805] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:54:05.805] INFO: ----------------------------------------------------------------------
[12:54:05.805] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:54:05.805] INFO: edge/corner pixel THR is adjusted
[12:54:05.805] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:54:06.763] INFO: Collecting data for 5 seconds...
[12:54:11.781] INFO: Done with hot pixel readout
[12:54:24.121] INFO: PixTest:: pg_setup set to default.
[12:54:24.122] INFO: 7 hot pixels found in step 0
[12:54:25.113] INFO: Collecting data for 5 seconds...
[12:54:30.131] INFO: Done with hot pixel readout
[12:54:42.303] INFO: PixTest:: pg_setup set to default.
[12:54:42.303] INFO: 1 hot pixels found in step 1
[12:54:43.300] INFO: Collecting data for 5 seconds...
[12:54:48.319] INFO: Done with hot pixel readout
[12:55:00.642] INFO: PixTest:: pg_setup set to default.
[12:55:00.643] INFO: 7 hot pixels found in step 2
[12:55:01.638] INFO: Collecting data for 5 seconds...
[12:55:06.659] INFO: Done with hot pixel readout
[12:55:18.876] INFO: PixTest:: pg_setup set to default.
[12:55:18.877] INFO: 5 hot pixels found in step 3
[12:55:19.872] INFO: Collecting data for 5 seconds...
[12:55:24.894] INFO: Done with hot pixel readout
[12:55:37.224] INFO: PixTest:: pg_setup set to default.
[12:55:37.225] INFO: 7 hot pixels found in step 4
[12:55:38.219] INFO: Collecting data for 5 seconds...
[12:55:43.235] INFO: Done with hot pixel readout
[12:55:55.366] INFO: PixTest:: pg_setup set to default.
[12:55:55.366] INFO: 2 hot pixels found in step 5
[12:55:56.361] INFO: Collecting data for 5 seconds...
[12:56:01.377] INFO: Done with hot pixel readout
[12:56:13.494] INFO: PixTest:: pg_setup set to default.
[12:56:13.495] INFO: 2 hot pixels found in step 6
[12:56:14.490] INFO: Collecting data for 5 seconds...
[12:56:19.506] INFO: Done with hot pixel readout
[12:56:31.733] INFO: PixTest:: pg_setup set to default.
[12:56:31.734] INFO: 2 hot pixels found in step 7
[12:56:32.730] INFO: Collecting data for 5 seconds...
[12:56:37.748] INFO: Done with hot pixel readout
[12:56:49.610] INFO: PixTest:: pg_setup set to default.
[12:56:49.611] INFO: 5 hot pixels found in step 8
[12:56:50.606] INFO: Collecting data for 5 seconds...
[12:56:55.623] INFO: Done with hot pixel readout
[12:57:07.393] INFO: PixTest:: pg_setup set to default.
[12:57:07.394] INFO: 4 hot pixels found in step 9
[12:57:08.389] INFO: Collecting data for 5 seconds...
[12:57:13.408] INFO: Done with hot pixel readout
[12:57:24.479] INFO: PixTest:: pg_setup set to default.
[12:57:24.480] INFO: 3 hot pixels found in step 10
[12:57:25.475] INFO: Collecting data for 5 seconds...
[12:57:30.488] INFO: Done with hot pixel readout
[12:57:40.647] INFO: PixTest:: pg_setup set to default.
[12:57:40.648] INFO: 1 hot pixels found in step 11
[12:57:41.644] INFO: Collecting data for 5 seconds...
[12:57:46.659] INFO: Done with hot pixel readout
[12:57:58.786] INFO: PixTest:: pg_setup set to default.
[12:57:58.787] INFO: 3 hot pixels found in step 12
[12:57:59.781] INFO: Collecting data for 5 seconds...
[12:58:04.798] INFO: Done with hot pixel readout
[12:58:16.918] INFO: PixTest:: pg_setup set to default.
[12:58:16.919] INFO: 3 hot pixels found in step 13
[12:58:17.915] INFO: Collecting data for 5 seconds...
[12:58:22.931] INFO: Done with hot pixel readout
[12:58:35.046] INFO: PixTest:: pg_setup set to default.
[12:58:35.046] INFO: 0 hot pixels found in step 14
[12:58:35.084] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:58:35.319] INFO: PixTest::trimHotPixels() done
[12:58:35.350] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C0.dat
[12:58:35.362] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C1.dat
[12:58:35.395] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C2.dat
[12:58:35.404] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C3.dat
[12:58:35.439] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C4.dat
[12:58:35.445] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C5.dat
[12:58:35.450] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C6.dat
[12:58:35.456] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C7.dat
[12:58:35.477] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C8.dat
[12:58:35.483] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C9.dat
[12:58:35.488] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C10.dat
[12:58:35.493] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C11.dat
[12:58:35.499] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C12.dat
[12:58:35.505] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C13.dat
[12:58:35.510] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C14.dat
[12:58:35.515] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C15.dat
[12:58:35.539] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//defaultMaskFile.dat
[12:58:35.605] INFO: enter test to run
[13:01:40.605] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:01:40.622] INFO: running: highrate
[13:01:40.627] INFO: ----------------------------------------------------------------------
[13:01:40.627] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:01:40.627] INFO: ----------------------------------------------------------------------
[13:01:40.627] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:01:40.627] INFO: edge/corner pixel THR is adjusted
[13:01:40.627] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:01:41.585] INFO: Collecting data for 1 seconds...
[13:01:42.588] INFO: Done with hot pixel readout
[13:01:46.790] INFO: PixTest:: pg_setup set to default.
[13:01:46.791] INFO: 0 hot pixels found in step 0
[13:01:46.796] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:01:46.878] INFO: PixTest::trimHotPixels() done
[13:01:46.878] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C0.dat
[13:01:46.888] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C1.dat
[13:01:46.893] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C2.dat
[13:01:46.899] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C3.dat
[13:01:46.904] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C4.dat
[13:01:46.909] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C5.dat
[13:01:46.915] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C6.dat
[13:01:46.920] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C7.dat
[13:01:46.925] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C8.dat
[13:01:46.931] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C9.dat
[13:01:46.936] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C10.dat
[13:01:46.941] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C11.dat
[13:01:46.947] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C12.dat
[13:01:46.952] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C13.dat
[13:01:46.957] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C14.dat
[13:01:46.963] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C15.dat
[13:01:46.968] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-G-2-34_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//defaultMaskFile.dat
[13:01:46.977] INFO: enter test to run
[13:02:00.077] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:02:00.077] INFO: running: xray
[13:02:00.109] INFO: ----------------------------------------------------------------------
[13:02:00.109] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:02:00.109] INFO: ----------------------------------------------------------------------
[13:02:01.104] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:02:12.273] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:02:41.950] INFO: Resuming triggers.
[13:02:53.121] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[13:03:22.568] INFO: Resuming triggers.
[13:03:33.738] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[13:04:03.609] INFO: Resuming triggers.
[13:04:14.782] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[13:04:44.724] INFO: Resuming triggers.
[13:04:55.894] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[13:05:25.890] INFO: Resuming triggers.
[13:05:37.061] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[13:06:05.372] INFO: Resuming triggers.
[13:06:16.545] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[13:06:44.951] INFO: Resuming triggers.
[13:06:56.123] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[13:07:25.300] INFO: Resuming triggers.
[13:07:36.292] INFO: data taking finished, elapsed time: 100 seconds.
[13:08:05.701] INFO: PixTest:: pg_setup set to default.
[13:08:05.704] INFO: PixTestXray::doPhRun() done
[13:08:06.258] INFO: enter test to run
[13:09:15.770] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:09:15.770] INFO: running: xray
[13:09:15.833] INFO: ----------------------------------------------------------------------
[13:09:15.833] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:09:15.833] INFO: ----------------------------------------------------------------------
[13:09:16.870] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:09:23.277] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:09:54.787] INFO: Resuming triggers.
[13:10:01.190] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[13:10:31.491] INFO: Resuming triggers.
[13:10:37.894] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[13:11:08.936] INFO: Resuming triggers.
[13:11:15.339] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[13:11:45.910] INFO: Resuming triggers.
[13:11:52.310] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[13:12:22.128] INFO: Resuming triggers.
[13:12:28.534] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[13:12:59.190] INFO: Resuming triggers.
[13:13:05.594] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[13:13:36.070] INFO: Resuming triggers.
[13:13:42.474] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[13:14:13.544] INFO: Resuming triggers.
[13:14:19.948] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[13:14:50.571] INFO: Resuming triggers.
[13:14:56.973] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[13:15:27.758] INFO: Resuming triggers.
[13:15:34.157] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[13:16:04.015] INFO: Resuming triggers.
[13:16:10.420] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[13:16:40.431] INFO: Resuming triggers.
[13:16:46.831] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:17:16.917] INFO: Resuming triggers.
[13:17:23.320] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[13:17:53.424] INFO: Resuming triggers.
[13:17:59.825] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[13:18:29.674] INFO: Resuming triggers.
[13:18:33.991] INFO: data taking finished, elapsed time: 100 seconds.
[13:18:54.783] INFO: PixTest:: pg_setup set to default.
[13:18:54.786] INFO: PixTestXray::doPhRun() done
[13:18:55.179] INFO: enter test to run
[13:20:15.791] INFO: test: HighRate no parameter change
[13:20:15.830] INFO: running: highrate
[13:20:16.345] INFO: ----------------------------------------------------------------------
[13:20:16.345] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:20:16.345] INFO: ----------------------------------------------------------------------
[13:20:16.652] INFO: Expecting 768 events.
[13:20:17.813] INFO: 768 events read in total (445ms).
[13:20:17.813] INFO: Test took 1294ms.
[13:20:20.577] INFO: Expecting 41600 events.
[13:20:23.601] INFO: 41600 events read in total (2497ms).
[13:20:23.602] INFO: Test took 3629ms.
[13:20:23.638] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:24.358] INFO: Expecting 41600 events.
[13:20:27.406] INFO: 41600 events read in total (2521ms).
[13:20:27.407] INFO: Test took 3709ms.
[13:20:27.444] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:28.168] INFO: Expecting 41600 events.
[13:20:31.412] INFO: 41600 events read in total (2717ms).
[13:20:31.414] INFO: Test took 3951ms.
[13:20:31.455] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:32.162] INFO: Expecting 41600 events.
[13:20:35.359] INFO: 41600 events read in total (2670ms).
[13:20:35.360] INFO: Test took 3883ms.
[13:20:35.398] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:36.111] INFO: Expecting 41600 events.
[13:20:39.218] INFO: 41600 events read in total (2580ms).
[13:20:39.219] INFO: Test took 3801ms.
[13:20:39.256] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:39.973] INFO: Expecting 41600 events.
[13:20:43.116] INFO: 41600 events read in total (2616ms).
[13:20:43.118] INFO: Test took 3841ms.
[13:20:43.156] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:43.869] INFO: Expecting 41600 events.
[13:20:47.101] INFO: 41600 events read in total (2705ms).
[13:20:47.102] INFO: Test took 3926ms.
[13:20:47.139] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:47.853] INFO: Expecting 41600 events.
[13:20:51.045] INFO: 41600 events read in total (2665ms).
[13:20:51.046] INFO: Test took 3889ms.
[13:20:51.084] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:51.789] INFO: Expecting 41600 events.
[13:20:54.913] INFO: 41600 events read in total (2597ms).
[13:20:54.914] INFO: Test took 3811ms.
[13:20:54.953] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:55.653] INFO: Expecting 41600 events.
[13:20:58.849] INFO: 41600 events read in total (2669ms).
[13:20:58.851] INFO: Test took 3879ms.
[13:20:58.888] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:59.606] INFO: Expecting 41600 events.
[13:21:02.826] INFO: 41600 events read in total (2694ms).
[13:21:02.827] INFO: Test took 3919ms.
[13:21:02.864] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:03.583] INFO: Expecting 41600 events.
[13:21:06.817] INFO: 41600 events read in total (2707ms).
[13:21:06.818] INFO: Test took 3934ms.
[13:21:06.856] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:07.568] INFO: Expecting 41600 events.
[13:21:10.860] INFO: 41600 events read in total (2766ms).
[13:21:10.861] INFO: Test took 3985ms.
[13:21:10.900] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:11.605] INFO: Expecting 41600 events.
[13:21:14.941] INFO: 41600 events read in total (2809ms).
[13:21:14.942] INFO: Test took 4023ms.
[13:21:14.980] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:15.693] INFO: Expecting 41600 events.
[13:21:19.019] INFO: 41600 events read in total (2800ms).
[13:21:19.020] INFO: Test took 4020ms.
[13:21:19.059] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:19.773] INFO: Expecting 41600 events.
[13:21:23.058] INFO: 41600 events read in total (2758ms).
[13:21:23.059] INFO: Test took 3981ms.
[13:21:23.098] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:23.813] INFO: Expecting 41600 events.
[13:21:27.038] INFO: 41600 events read in total (2699ms).
[13:21:27.039] INFO: Test took 3921ms.
[13:21:27.078] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:27.792] INFO: Expecting 41600 events.
[13:21:31.055] INFO: 41600 events read in total (2736ms).
[13:21:31.056] INFO: Test took 3959ms.
[13:21:31.097] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:31.807] INFO: Expecting 41600 events.
[13:21:35.104] INFO: 41600 events read in total (2770ms).
[13:21:35.105] INFO: Test took 3986ms.
[13:21:35.144] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:35.860] INFO: Expecting 41600 events.
[13:21:39.031] INFO: 41600 events read in total (2644ms).
[13:21:39.032] INFO: Test took 3868ms.
[13:21:39.071] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:39.727] INFO: enter test to run
[13:22:04.980] INFO: test: HighRate no parameter change
[13:22:04.980] INFO: running: highrate
[13:22:05.230] INFO: ----------------------------------------------------------------------
[13:22:05.230] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:22:05.230] INFO: ----------------------------------------------------------------------
[13:22:06.034] INFO: Expecting 208000 events.
[13:22:17.591] INFO: 208000 events read in total (11030ms).
[13:22:17.595] INFO: Test took 12171ms.
[13:22:17.757] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:18.044] INFO: number of dead pixels (per ROC): 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:22:18.122] INFO: number of red-efficiency pixels: 72 50 91 125 215 193 132 107 85 117 128 112 113 81 26 24
[13:22:18.122] INFO: number of X-ray hits detected: 67367 48190 73614 116355 126145 124867 122223 89337 87353 105569 109802 90066 94872 59861 24428 30622
[13:22:18.122] 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:22:18.122] INFO: number of Vcal hits detected: 207928 207950 207811 207872 207781 207799 207864 207887 207913 207881 207867 207887 207885 207916 207974 207976
[13:22:18.176] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[13:22:18.176] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[13:22:18.176] INFO: X-ray hit rate [MHz/cm2]: 19.7 14.1 21.6 34.1 37.0 36.6 35.8 26.2 25.6 30.9 32.2 26.4 27.8 17.5 7.2 9.0
[13:22:18.176] INFO: PixTestHighRate::doXPixelAlive() done
[13:22:18.243] INFO: PixTest:: pg_setup set to default.
[13:22:18.452] INFO: enter test to run
[13:22:35.868] INFO: test: HighRate no parameter change
[13:22:35.868] INFO: running: highrate
[13:22:36.238] INFO: ----------------------------------------------------------------------
[13:22:36.238] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:22:36.238] INFO: ----------------------------------------------------------------------
[13:22:37.028] INFO: Expecting 208000 events.
[13:22:50.021] INFO: 208000 events read in total (12466ms).
[13:22:50.027] INFO: Test took 13608ms.
[13:22:50.366] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:50.677] INFO: number of dead pixels (per ROC): 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:22:50.677] INFO: number of red-efficiency pixels: 245 151 291 426 661 690 456 273 324 344 417 292 385 236 65 64
[13:22:50.677] INFO: number of X-ray hits detected: 138641 98975 151692 238868 257300 255408 250345 181821 180055 215865 224610 185464 195000 122252 50009 63699
[13:22:50.677] 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:22:50.677] INFO: number of Vcal hits detected: 207729 207843 207582 207542 207257 207226 207501 207697 207657 207629 207547 207694 207584 207747 207935 207936
[13:22:50.678] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.8 99.9 99.8 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[13:22:50.678] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.6 99.6 99.8 99.9 99.8 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[13:22:50.678] INFO: X-ray hit rate [MHz/cm2]: 40.6 29.0 44.5 70.0 75.4 74.9 73.4 53.3 52.8 63.3 65.8 54.4 57.2 35.8 14.7 18.7
[13:22:50.678] INFO: PixTestHighRate::doXPixelAlive() done
[13:22:50.723] INFO: PixTest:: pg_setup set to default.
[13:22:50.738] INFO: enter test to run
[13:23:03.212] INFO: test: HighRate no parameter change
[13:23:03.212] INFO: running: highrate
[13:23:03.213] INFO: ----------------------------------------------------------------------
[13:23:03.213] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:23:03.213] INFO: ----------------------------------------------------------------------
[13:23:03.829] INFO: Expecting 208000 events.
[13:23:18.803] INFO: 208000 events read in total (14447ms).
[13:23:18.811] INFO: Test took 15589ms.
[13:23:19.301] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:19.664] INFO: number of dead pixels (per ROC): 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:23:19.664] INFO: number of red-efficiency pixels: 512 283 674 968 1593 1610 1105 626 592 822 953 613 912 553 105 118
[13:23:19.664] INFO: number of X-ray hits detected: 207305 148650 227816 357857 386737 383732 373912 272180 269329 323270 336286 277391 292495 184109 76629 95041
[13:23:19.664] 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:23:19.664] INFO: number of Vcal hits detected: 207358 207683 206979 206798 205739 205674 206628 207243 207301 207025 206827 207278 206916 207334 207893 207878
[13:23:19.664] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.6 99.5 99.0 99.0 99.4 99.7 99.7 99.6 99.5 99.7 99.5 99.7 100.0 99.9
[13:23:19.975] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.5 99.4 98.9 98.9 99.3 99.6 99.7 99.5 99.4 99.7 99.5 99.7 99.9 99.9
[13:23:19.975] INFO: X-ray hit rate [MHz/cm2]: 60.8 43.6 66.8 104.9 113.4 112.5 109.6 79.8 78.9 94.8 98.6 81.3 85.7 54.0 22.5 27.9
[13:23:19.975] INFO: PixTestHighRate::doXPixelAlive() done
[13:23:20.021] INFO: PixTest:: pg_setup set to default.
[13:23:20.034] INFO: enter test to run
[13:23:30.324] INFO: test: exit no parameter change
[13:23:34.024] QUIET: Connection to board 33 closed.
[13:23:34.781] INFO: pXar: this is the end, my friend