[10:55:40.428] INFO: *** Welcome to pxar ***
[10:55:40.428] INFO: *** Today: 2016/05/17
[10:55:40.446] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:55:40.446] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//dacParameters35_C15.dat
[10:55:40.447] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:55:40.447] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//defaultMaskFile.dat
[10:55:40.447] INFO: MASKED Roc 0 col/row: 1 1
[10:55:40.447] INFO: MASKED Roc 0 col/row: 1 5
[10:55:40.447] INFO: MASKED Roc 0 col/row: 1 6
[10:55:40.447] INFO: MASKED Roc 0 col/row: 1 8
[10:55:40.447] INFO: MASKED Roc 0 col/row: 2 6
[10:55:40.447] INFO: MASKED Roc 0 col/row: 2 7
[10:55:40.447] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C15.dat
[10:55:40.447] INFO: masking Roc 0 col/row: 1 1
[10:55:40.447] INFO: masking Roc 0 col/row: 1 5
[10:55:40.447] INFO: masking Roc 0 col/row: 1 6
[10:55:40.447] INFO: masking Roc 0 col/row: 1 8
[10:55:40.447] INFO: masking Roc 0 col/row: 2 6
[10:55:40.447] INFO: masking Roc 0 col/row: 2 7
[10:55:40.520] INFO: clk: 4
[10:55:40.520] INFO: ctr: 4
[10:55:40.520] INFO: sda: 19
[10:55:40.520] INFO: tin: 9
[10:55:40.520] INFO: level: 15
[10:55:40.520] INFO: triggerdelay: 0
[10:55:40.520] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:55:40.520] INFO: Log level: INFO
[10:55:40.535] QUIET: Connection to board DTB_WREKRL opened.
[10:55:40.538] 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:
------------------------------------------------------
[10:55:40.541] INFO: RPC call hashes of host and DTB match: 398089610
[10:55:42.079] INFO: DUT info:
[10:55:42.079] INFO: The DUT currently contains the following objects:
[10:55:42.079] INFO: 2 TBM Cores tbm08c (2 ON)
[10:55:42.079] INFO: TBM Core alpha (0): 7 registers set
[10:55:42.079] INFO: TBM Core beta (1): 7 registers set
[10:55:42.079] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:55:42.079] INFO: ROC 0: 19 DACs set, Pixels: 6 masked, 0 active.
[10:55:42.079] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:55:42.079] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:55:42.079] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:55:42.079] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:55:42.079] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:55:42.079] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:55:42.079] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:55:42.079] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:55:42.079] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:55:42.079] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:55:42.079] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:55:42.079] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:55:42.079] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:55:42.079] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:55:42.080] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:55:42.483] INFO: enter 'restricted' command line mode
[10:55:42.483] INFO: enter test to run
[10:55:55.592] INFO: test: PixelAlive no parameter change
[10:55:55.592] INFO: running: pixelalive
[10:55:55.601] INFO: ----------------------------------------------------------------------
[10:55:55.601] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:55:55.601] INFO: ----------------------------------------------------------------------
[10:55:55.604] INFO: ROC 0 masking pixel 1/1
[10:55:55.604] INFO: ROC 0 masking pixel 1/5
[10:55:55.604] INFO: ROC 0 masking pixel 1/6
[10:55:55.604] INFO: ROC 0 masking pixel 1/8
[10:55:55.604] INFO: ROC 0 masking pixel 2/6
[10:55:55.604] INFO: ROC 0 masking pixel 2/7
[10:55:55.927] INFO: Expecting 41600 events.
[10:56:00.256] INFO: 41600 events read in total (3611ms).
[10:56:00.424] INFO: Test took 4820ms.
[10:56:00.438] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:00.714] INFO: PixTestAlive::aliveTest() done
[10:56:00.714] INFO: number of dead pixels (per ROC): 6 0 0 0 0 2 0 1 0 9 0 0 0 0 0 0
[10:56:00.715] INFO: ROC 0 masking pixel 1/1
[10:56:00.715] INFO: ROC 0 masking pixel 1/5
[10:56:00.715] INFO: ROC 0 masking pixel 1/6
[10:56:00.715] INFO: ROC 0 masking pixel 1/8
[10:56:00.715] INFO: ROC 0 masking pixel 2/6
[10:56:00.715] INFO: ROC 0 masking pixel 2/7
[10:56:00.746] INFO: enter test to run
[10:56:48.304] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:56:48.304] INFO: running: highrate
[10:56:48.304] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:56:48.463] INFO: ----------------------------------------------------------------------
[10:56:48.463] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:56:48.463] INFO: ----------------------------------------------------------------------
[10:56:48.463] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:56:48.463] INFO: edge/corner pixel THR is adjusted
[10:56:48.463] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:56:49.422] INFO: Collecting data for 5 seconds...
[10:56:54.441] INFO: Done with hot pixel readout
[10:57:06.507] INFO: PixTest:: pg_setup set to default.
[10:57:06.507] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:06.507] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:06.507] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:06.507] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:06.508] INFO: 10 hot pixels found in step 0
[10:57:07.525] INFO: Collecting data for 5 seconds...
[10:57:12.543] INFO: Done with hot pixel readout
[10:57:24.394] INFO: PixTest:: pg_setup set to default.
[10:57:24.394] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:24.394] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:24.394] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:24.394] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:24.395] INFO: 9 hot pixels found in step 1
[10:57:25.384] INFO: Collecting data for 5 seconds...
[10:57:30.402] INFO: Done with hot pixel readout
[10:57:42.247] INFO: PixTest:: pg_setup set to default.
[10:57:42.247] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:42.247] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:42.247] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:42.247] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:42.248] INFO: 10 hot pixels found in step 2
[10:57:43.237] INFO: Collecting data for 5 seconds...
[10:57:48.254] INFO: Done with hot pixel readout
[10:58:00.037] INFO: PixTest:: pg_setup set to default.
[10:58:00.037] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:00.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:00.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:00.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:00.039] INFO: 8 hot pixels found in step 3
[10:58:01.028] INFO: Collecting data for 5 seconds...
[10:58:06.044] INFO: Done with hot pixel readout
[10:58:17.813] INFO: PixTest:: pg_setup set to default.
[10:58:17.813] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:17.814] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:17.814] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:17.814] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:17.814] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:17.814] INFO: 13 hot pixels found in step 4
[10:58:18.804] INFO: Collecting data for 5 seconds...
[10:58:23.821] INFO: Done with hot pixel readout
[10:58:35.595] INFO: PixTest:: pg_setup set to default.
[10:58:35.595] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:35.595] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:35.595] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:35.595] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:35.596] INFO: 5 hot pixels found in step 5
[10:58:36.586] INFO: Collecting data for 5 seconds...
[10:58:41.602] INFO: Done with hot pixel readout
[10:58:53.394] INFO: PixTest:: pg_setup set to default.
[10:58:53.394] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:53.394] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:53.394] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:53.394] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:58:53.394] INFO: 11 hot pixels found in step 6
[10:58:54.383] INFO: Collecting data for 5 seconds...
[10:58:59.400] INFO: Done with hot pixel readout
[10:59:11.176] INFO: PixTest:: pg_setup set to default.
[10:59:11.176] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:59:11.176] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:59:11.176] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:59:11.176] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:59:11.177] INFO: 10 hot pixels found in step 7
[10:59:12.166] INFO: Collecting data for 5 seconds...
[10:59:17.183] INFO: Done with hot pixel readout
[10:59:28.937] INFO: PixTest:: pg_setup set to default.
[10:59:28.937] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:59:28.937] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:59:28.937] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:59:28.937] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:59:28.937] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:59:28.938] INFO: 10 hot pixels found in step 8
[10:59:29.926] INFO: Collecting data for 5 seconds...
[10:59:34.942] INFO: Done with hot pixel readout
[10:59:46.754] INFO: PixTest:: pg_setup set to default.
[10:59:46.754] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:59:46.754] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:59:46.754] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:59:46.754] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:59:46.755] INFO: 8 hot pixels found in step 9
[10:59:47.744] INFO: Collecting data for 5 seconds...
[10:59:52.760] INFO: Done with hot pixel readout
[11:00:04.508] INFO: PixTest:: pg_setup set to default.
[11:00:04.508] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:04.508] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:04.508] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:04.508] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:04.509] INFO: 7 hot pixels found in step 10
[11:00:05.497] INFO: Collecting data for 5 seconds...
[11:00:10.513] INFO: Done with hot pixel readout
[11:00:22.024] INFO: PixTest:: pg_setup set to default.
[11:00:22.024] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:22.024] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:22.024] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:22.024] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:22.025] INFO: 9 hot pixels found in step 11
[11:00:23.014] INFO: Collecting data for 5 seconds...
[11:00:28.031] INFO: Done with hot pixel readout
[11:00:39.824] INFO: PixTest:: pg_setup set to default.
[11:00:39.824] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:39.824] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:39.824] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:39.824] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:39.825] INFO: 8 hot pixels found in step 12
[11:00:40.813] INFO: Collecting data for 5 seconds...
[11:00:45.830] INFO: Done with hot pixel readout
[11:00:57.579] INFO: PixTest:: pg_setup set to default.
[11:00:57.579] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:57.579] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:57.579] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:57.579] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:00:57.580] INFO: 9 hot pixels found in step 13
[11:00:58.569] INFO: Collecting data for 5 seconds...
[11:01:03.586] INFO: Done with hot pixel readout
[11:01:15.409] INFO: PixTest:: pg_setup set to default.
[11:01:15.409] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:01:15.409] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:01:15.409] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:01:15.409] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:01:15.410] INFO: 13 hot pixels found in step 14
[11:01:15.442] INFO: 13 hot pixels could not be trimmed and have been masked.
[11:01:15.446] INFO: PixTest::trimHotPixels() done
[11:01:15.450] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C0.dat
[11:01:15.456] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C1.dat
[11:01:15.461] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C2.dat
[11:01:15.467] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C3.dat
[11:01:15.472] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C4.dat
[11:01:15.477] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C5.dat
[11:01:15.482] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C6.dat
[11:01:15.488] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C7.dat
[11:01:15.493] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C8.dat
[11:01:15.498] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C9.dat
[11:01:15.503] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C10.dat
[11:01:15.508] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C11.dat
[11:01:15.514] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C12.dat
[11:01:15.519] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C13.dat
[11:01:15.524] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C14.dat
[11:01:15.529] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C15.dat
[11:01:15.535] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//defaultMaskFile.dat
[11:01:15.545] INFO: enter test to run
[11:01:47.421] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[11:01:47.422] INFO: running: highrate
[11:01:47.426] INFO: ----------------------------------------------------------------------
[11:01:47.426] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:01:47.426] INFO: ----------------------------------------------------------------------
[11:01:47.426] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:01:47.426] INFO: edge/corner pixel THR is adjusted
[11:01:47.426] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:01:48.384] INFO: Collecting data for 1 seconds...
[11:01:49.388] INFO: Done with hot pixel readout
[11:01:53.463] INFO: PixTest:: pg_setup set to default.
[11:01:53.464] INFO: 0 hot pixels found in step 0
[11:01:53.469] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:01:53.556] INFO: PixTest::trimHotPixels() done
[11:01:53.557] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C0.dat
[11:01:53.568] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C1.dat
[11:01:53.574] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C2.dat
[11:01:53.579] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C3.dat
[11:01:53.585] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C4.dat
[11:01:53.590] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C5.dat
[11:01:53.595] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C6.dat
[11:01:53.600] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C7.dat
[11:01:53.605] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C8.dat
[11:01:53.610] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C9.dat
[11:01:53.616] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C10.dat
[11:01:53.621] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C11.dat
[11:01:53.626] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C12.dat
[11:01:53.631] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C13.dat
[11:01:53.636] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C14.dat
[11:01:53.642] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C15.dat
[11:01:53.647] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-2-07_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//defaultMaskFile.dat
[11:01:53.657] INFO: enter test to run
[11:02:25.653] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:02:25.653] INFO: running: xray
[11:02:25.654] INFO: ----------------------------------------------------------------------
[11:02:25.654] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:02:25.654] INFO: ----------------------------------------------------------------------
[11:02:25.658] INFO: ROC 0 masking pixel 1/1
[11:02:25.658] INFO: ROC 0 masking pixel 1/5
[11:02:25.658] INFO: ROC 0 masking pixel 1/6
[11:02:25.658] INFO: ROC 0 masking pixel 1/8
[11:02:25.658] INFO: ROC 0 masking pixel 2/6
[11:02:25.658] INFO: ROC 0 masking pixel 2/7
[11:02:26.617] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:02:37.001] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:03:07.494] INFO: Resuming triggers.
[11:03:18.877] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[11:03:48.256] INFO: Resuming triggers.
[11:03:59.640] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[11:04:29.146] INFO: Resuming triggers.
[11:04:40.527] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[11:05:09.799] INFO: Resuming triggers.
[11:05:21.181] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[11:05:50.895] INFO: Resuming triggers.
[11:06:02.274] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[11:06:31.527] INFO: Resuming triggers.
[11:06:42.907] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[11:07:12.799] INFO: Resuming triggers.
[11:07:24.177] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[11:07:54.379] INFO: Resuming triggers.
[11:08:03.673] INFO: data taking finished, elapsed time: 100 seconds.
[11:08:28.384] INFO: PixTest:: pg_setup set to default.
[11:08:28.387] INFO: PixTestXray::doPhRun() done
[11:08:28.528] INFO: enter test to run
[11:08:56.194] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:08:56.194] INFO: running: xray
[11:08:56.195] INFO: ----------------------------------------------------------------------
[11:08:56.195] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:08:56.195] INFO: ----------------------------------------------------------------------
[11:08:56.208] INFO: ROC 0 masking pixel 1/1
[11:08:56.208] INFO: ROC 0 masking pixel 1/5
[11:08:56.208] INFO: ROC 0 masking pixel 1/6
[11:08:56.208] INFO: ROC 0 masking pixel 1/8
[11:08:56.208] INFO: ROC 0 masking pixel 2/6
[11:08:56.208] INFO: ROC 0 masking pixel 2/7
[11:08:57.175] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:09:03.740] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[11:09:33.837] INFO: Resuming triggers.
[11:09:40.401] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[11:10:10.577] INFO: Resuming triggers.
[11:10:17.142] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[11:10:47.157] INFO: Resuming triggers.
[11:10:53.729] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[11:11:23.886] INFO: Resuming triggers.
[11:11:30.455] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[11:12:00.556] INFO: Resuming triggers.
[11:12:07.122] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[11:12:37.138] INFO: Resuming triggers.
[11:12:43.706] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[11:13:13.870] INFO: Resuming triggers.
[11:13:20.438] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[11:13:50.555] INFO: Resuming triggers.
[11:13:57.126] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[11:14:27.332] INFO: Resuming triggers.
[11:14:33.904] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[11:15:04.143] INFO: Resuming triggers.
[11:15:10.713] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[11:15:40.812] INFO: Resuming triggers.
[11:15:47.382] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[11:16:17.533] INFO: Resuming triggers.
[11:16:24.101] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[11:16:55.142] INFO: Resuming triggers.
[11:17:01.711] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[11:17:32.186] INFO: Resuming triggers.
[11:17:38.752] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[11:18:09.706] INFO: Resuming triggers.
[11:18:11.516] INFO: data taking finished, elapsed time: 100 seconds.
[11:18:20.413] INFO: PixTest:: pg_setup set to default.
[11:18:20.416] INFO: PixTestXray::doPhRun() done
[11:18:20.568] INFO: enter test to run
[11:18:53.966] INFO: test: HighRate no parameter change
[11:18:53.966] INFO: running: highrate
[11:18:53.967] INFO: ----------------------------------------------------------------------
[11:18:53.967] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:18:53.967] INFO: ----------------------------------------------------------------------
[11:18:54.107] INFO: Expecting 768 events.
[11:18:55.245] INFO: 768 events read in total (418ms).
[11:18:55.245] INFO: Test took 1272ms.
[11:18:55.249] INFO: ROC 0 masking pixel 1/1
[11:18:55.249] INFO: ROC 0 masking pixel 1/5
[11:18:55.249] INFO: ROC 0 masking pixel 1/6
[11:18:55.249] INFO: ROC 0 masking pixel 1/8
[11:18:55.249] INFO: ROC 0 masking pixel 2/6
[11:18:55.249] INFO: ROC 0 masking pixel 2/7
[11:18:56.048] INFO: Expecting 41600 events.
[11:18:59.226] INFO: 41600 events read in total (2651ms).
[11:18:59.227] INFO: Test took 3975ms.
[11:18:59.262] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:59.969] INFO: Expecting 41600 events.
[11:19:03.182] INFO: 41600 events read in total (2686ms).
[11:19:03.183] INFO: Test took 3903ms.
[11:19:03.218] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:03.942] INFO: Expecting 41600 events.
[11:19:07.234] INFO: 41600 events read in total (2765ms).
[11:19:07.235] INFO: Test took 3999ms.
[11:19:07.270] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:07.988] INFO: Expecting 41600 events.
[11:19:11.290] INFO: 41600 events read in total (2776ms).
[11:19:11.291] INFO: Test took 4003ms.
[11:19:11.336] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:12.028] INFO: Expecting 41600 events.
[11:19:15.317] INFO: 41600 events read in total (2762ms).
[11:19:15.318] INFO: Test took 3956ms.
[11:19:15.354] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:16.071] INFO: Expecting 41600 events.
[11:19:19.356] INFO: 41600 events read in total (2758ms).
[11:19:19.357] INFO: Test took 3986ms.
[11:19:19.393] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:20.114] INFO: Expecting 41600 events.
[11:19:23.437] INFO: 41600 events read in total (2797ms).
[11:19:23.438] INFO: Test took 4027ms.
[11:19:23.472] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:24.192] INFO: Expecting 41600 events.
[11:19:27.490] INFO: 41600 events read in total (2771ms).
[11:19:27.491] INFO: Test took 4001ms.
[11:19:27.527] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:28.245] INFO: Expecting 41600 events.
[11:19:31.492] INFO: 41600 events read in total (2720ms).
[11:19:31.492] INFO: Test took 3947ms.
[11:19:31.528] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:32.250] INFO: Expecting 41600 events.
[11:19:35.486] INFO: 41600 events read in total (2709ms).
[11:19:35.487] INFO: Test took 3942ms.
[11:19:35.522] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:36.245] INFO: Expecting 41600 events.
[11:19:39.503] INFO: 41600 events read in total (2731ms).
[11:19:39.504] INFO: Test took 3965ms.
[11:19:39.538] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:40.263] INFO: Expecting 41600 events.
[11:19:43.512] INFO: 41600 events read in total (2722ms).
[11:19:43.513] INFO: Test took 3957ms.
[11:19:43.547] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:44.272] INFO: Expecting 41600 events.
[11:19:47.512] INFO: 41600 events read in total (2714ms).
[11:19:47.513] INFO: Test took 3947ms.
[11:19:47.547] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:48.274] INFO: Expecting 41600 events.
[11:19:51.518] INFO: 41600 events read in total (2717ms).
[11:19:51.518] INFO: Test took 3952ms.
[11:19:51.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:52.279] INFO: Expecting 41600 events.
[11:19:55.522] INFO: 41600 events read in total (2716ms).
[11:19:55.523] INFO: Test took 3952ms.
[11:19:55.557] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:56.279] INFO: Expecting 41600 events.
[11:19:59.522] INFO: 41600 events read in total (2716ms).
[11:19:59.523] INFO: Test took 3947ms.
[11:19:59.557] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:00.282] INFO: Expecting 41600 events.
[11:20:03.515] INFO: 41600 events read in total (2706ms).
[11:20:03.516] INFO: Test took 3940ms.
[11:20:03.551] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:04.273] INFO: Expecting 41600 events.
[11:20:07.523] INFO: 41600 events read in total (2723ms).
[11:20:07.524] INFO: Test took 3954ms.
[11:20:07.559] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:08.279] INFO: Expecting 41600 events.
[11:20:11.443] INFO: 41600 events read in total (2637ms).
[11:20:11.444] INFO: Test took 3865ms.
[11:20:11.479] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:12.194] INFO: Expecting 41600 events.
[11:20:15.216] INFO: 41600 events read in total (2495ms).
[11:20:15.217] INFO: Test took 3719ms.
[11:20:15.252] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:15.621] INFO: enter test to run
[11:20:24.893] INFO: test: HighRate no parameter change
[11:20:24.893] INFO: running: highrate
[11:20:24.894] INFO: ----------------------------------------------------------------------
[11:20:24.894] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:20:24.894] INFO: ----------------------------------------------------------------------
[11:20:24.899] INFO: ROC 0 masking pixel 1/1
[11:20:24.899] INFO: ROC 0 masking pixel 1/5
[11:20:24.899] INFO: ROC 0 masking pixel 1/6
[11:20:24.899] INFO: ROC 0 masking pixel 1/8
[11:20:24.899] INFO: ROC 0 masking pixel 2/6
[11:20:24.899] INFO: ROC 0 masking pixel 2/7
[11:20:25.503] INFO: Expecting 208000 events.
[11:20:37.404] INFO: 208000 events read in total (11375ms).
[11:20:37.407] INFO: Test took 12507ms.
[11:20:37.557] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:37.810] INFO: number of dead pixels (per ROC): 6 0 0 0 0 2 0 1 0 9 0 0 0 0 0 0
[11:20:37.810] INFO: number of red-efficiency pixels: 82 48 92 121 136 146 148 118 127 134 112 122 93 72 30 29
[11:20:37.810] INFO: number of X-ray hits detected: 68194 46269 67476 106124 113784 111923 111266 78744 81412 101099 99346 90118 88424 50861 23704 25087
[11:20:37.810] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:20:37.810] INFO: number of Vcal hits detected: 207622 207952 207900 207878 207863 207752 207850 207833 207871 207423 207886 207876 207906 207926 207970 207971
[11:20:37.810] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[11:20:37.810] INFO: Vcal hit overall efficiency (%): 99.8 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.7 99.9 99.9 100.0 100.0 100.0 100.0
[11:20:37.810] INFO: X-ray hit rate [MHz/cm2]: 20.0 13.6 19.8 31.1 33.4 32.8 32.6 23.1 23.9 29.6 29.1 26.4 25.9 14.9 6.9 7.4
[11:20:37.810] INFO: PixTestHighRate::doXPixelAlive() done
[11:20:37.860] INFO: PixTest:: pg_setup set to default.
[11:20:37.875] INFO: enter test to run
[11:21:08.333] INFO: test: HighRate no parameter change
[11:21:08.333] INFO: running: highrate
[11:21:08.334] INFO: ----------------------------------------------------------------------
[11:21:08.334] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:21:08.334] INFO: ----------------------------------------------------------------------
[11:21:08.343] INFO: ROC 0 masking pixel 1/1
[11:21:08.343] INFO: ROC 0 masking pixel 1/5
[11:21:08.343] INFO: ROC 0 masking pixel 1/6
[11:21:08.343] INFO: ROC 0 masking pixel 1/8
[11:21:08.343] INFO: ROC 0 masking pixel 2/6
[11:21:08.343] INFO: ROC 0 masking pixel 2/7
[11:21:08.951] INFO: Expecting 208000 events.
[11:21:22.701] INFO: 208000 events read in total (13223ms).
[11:21:22.707] INFO: Test took 14363ms.
[11:21:23.021] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:23.325] INFO: number of dead pixels (per ROC): 6 0 0 0 0 2 0 1 0 9 0 0 0 0 0 0
[11:21:23.326] INFO: number of red-efficiency pixels: 233 183 318 408 430 574 424 352 297 335 427 296 289 181 55 51
[11:21:23.326] INFO: number of X-ray hits detected: 145603 98195 143459 223276 242006 235123 235547 166976 173216 214700 210140 191368 188984 108309 50317 53616
[11:21:23.326] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:21:23.326] INFO: number of Vcal hits detected: 207450 207811 207654 207559 207544 207246 207534 207570 207691 207200 207556 207686 207702 207809 207942 207949
[11:21:23.326] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[11:21:23.326] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.8 99.8 99.8 99.6 99.8 99.8 99.9 99.6 99.8 99.8 99.9 99.9 100.0 100.0
[11:21:23.326] INFO: X-ray hit rate [MHz/cm2]: 42.7 28.8 42.0 65.4 70.9 68.9 69.0 48.9 50.8 62.9 61.6 56.1 55.4 31.7 14.7 15.7
[11:21:23.326] INFO: PixTestHighRate::doXPixelAlive() done
[11:21:23.376] INFO: PixTest:: pg_setup set to default.
[11:21:23.392] INFO: enter test to run
[11:21:44.189] INFO: test: HighRate no parameter change
[11:21:44.189] INFO: running: highrate
[11:21:44.190] INFO: ----------------------------------------------------------------------
[11:21:44.190] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:21:44.190] INFO: ----------------------------------------------------------------------
[11:21:44.198] INFO: ROC 0 masking pixel 1/1
[11:21:44.198] INFO: ROC 0 masking pixel 1/5
[11:21:44.198] INFO: ROC 0 masking pixel 1/6
[11:21:44.198] INFO: ROC 0 masking pixel 1/8
[11:21:44.198] INFO: ROC 0 masking pixel 2/6
[11:21:44.198] INFO: ROC 0 masking pixel 2/7
[11:21:44.810] INFO: Expecting 208000 events.
[11:22:00.498] INFO: 208000 events read in total (15161ms).
[11:22:00.506] INFO: Test took 16307ms.
[11:22:00.956] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:01.312] INFO: number of dead pixels (per ROC): 6 0 0 0 0 2 0 1 0 9 0 0 0 0 0 0
[11:22:01.312] INFO: number of red-efficiency pixels: 496 313 666 838 975 1254 946 734 711 795 994 600 599 438 92 90
[11:22:01.312] INFO: number of X-ray hits detected: 217141 147770 214825 334239 360923 352903 351997 250952 260033 321731 315269 286077 283748 161973 75858 80255
[11:22:01.312] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:22:01.312] INFO: number of Vcal hits detected: 207114 207649 207113 207033 206840 206268 206854 207038 207158 206629 206799 207308 207326 207483 207906 207910
[11:22:01.312] INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.6 99.6 99.5 99.3 99.5 99.6 99.6 99.6 99.5 99.7 99.7 99.8 100.0 100.0
[11:22:01.312] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.6 99.5 99.4 99.2 99.4 99.5 99.6 99.3 99.4 99.7 99.7 99.8 100.0 100.0
[11:22:01.312] INFO: X-ray hit rate [MHz/cm2]: 63.6 43.3 63.0 98.0 105.8 103.4 103.2 73.6 76.2 94.3 92.4 83.9 83.2 47.5 22.2 23.5
[11:22:01.312] INFO: PixTestHighRate::doXPixelAlive() done
[11:22:01.364] INFO: PixTest:: pg_setup set to default.
[11:22:01.377] INFO: enter test to run
[11:22:05.116] INFO: test: exit no parameter change
[11:22:05.431] QUIET: Connection to board 33 closed.
[11:22:05.432] INFO: pXar: this is the end, my friend