[12:31:35.521] INFO: *** Welcome to pxar ***
[12:31:35.521] INFO: *** Today: 2016/09/02
[12:31:36.559] INFO: *** Version: v1.9.0-818-g96727
[12:31:36.559] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//dacParameters35_C15.dat
[12:31:36.668] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:31:36.668] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//defaultMaskFile.dat
[12:31:36.676] INFO: MASKED Roc 0 col/row: 1 7
[12:31:36.676] INFO: MASKED Roc 0 col/row: 2 7
[12:31:36.676] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C15.dat
[12:31:36.676] INFO: masking Roc 0 col/row: 1 7
[12:31:36.676] INFO: masking Roc 0 col/row: 2 7
[12:31:36.789] INFO: clk: 4
[12:31:36.789] INFO: ctr: 4
[12:31:36.789] INFO: sda: 19
[12:31:36.789] INFO: tin: 9
[12:31:36.789] INFO: level: 15
[12:31:36.789] INFO: triggerdelay: 0
[12:31:36.789] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:31:36.789] INFO: Log level: INFO
[12:31:36.806] QUIET: Connection to board DTB_WREKRL opened.
[12:31:36.810] 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:31:36.812] INFO: RPC call hashes of host and DTB match: 398089610
[12:31:38.347] INFO: DUT info:
[12:31:38.347] INFO: The DUT currently contains the following objects:
[12:31:38.347] INFO: 2 TBM Cores tbm08c (2 ON)
[12:31:38.347] INFO: TBM Core alpha (0): 7 registers set
[12:31:38.347] INFO: TBM Core beta (1): 7 registers set
[12:31:38.347] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:31:38.347] INFO: ROC 0: 19 DACs set, Pixels: 2 masked, 0 active.
[12:31:38.347] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:31:38.348] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:31:38.348] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:31:38.348] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:31:38.348] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:31:38.348] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:31:38.348] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:31:38.348] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:31:38.348] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:31:38.348] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:31:38.348] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:31:38.348] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:31:38.348] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:31:38.348] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:31:38.348] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:31:38.800] INFO: enter 'restricted' command line mode
[12:31:38.800] INFO: enter test to run
[12:31:55.427] INFO: test: PixelAlive no parameter change
[12:31:55.427] INFO: running: pixelalive
[12:31:55.487] INFO: ----------------------------------------------------------------------
[12:31:55.487] 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:31:55.487] INFO: ----------------------------------------------------------------------
[12:31:55.489] INFO: ROC 0 masking pixel 1/7
[12:31:55.489] INFO: ROC 0 masking pixel 2/7
[12:31:55.808] INFO: Expecting 41600 events.
[12:32:00.161] INFO: 41600 events read in total (3635ms).
[12:32:00.330] INFO: Test took 4841ms.
[12:32:00.342] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:32:00.951] INFO: PixTestAlive::aliveTest() done
[12:32:00.951] INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 1 85 52 52 51 6 0 0 0
[12:32:00.952] INFO: ROC 0 masking pixel 1/7
[12:32:00.952] INFO: ROC 0 masking pixel 2/7
[12:32:01.211] INFO: enter test to run
[12:32:21.787] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:32:21.787] INFO: running: highrate
[12:32:21.799] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:32:22.482] INFO: ----------------------------------------------------------------------
[12:32:22.482] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:32:22.482] INFO: ----------------------------------------------------------------------
[12:32:22.482] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:32:22.483] INFO: edge/corner pixel THR is adjusted
[12:32:22.483] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:32:23.441] INFO: Collecting data for 5 seconds...
[12:32:28.459] INFO: Done with hot pixel readout
[12:32:40.032] INFO: PixTest:: pg_setup set to default.
[12:32:40.032] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:32:40.032] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:32:40.033] INFO: 9 hot pixels found in step 0
[12:32:41.042] INFO: Collecting data for 5 seconds...
[12:32:46.060] INFO: Done with hot pixel readout
[12:32:57.403] INFO: PixTest:: pg_setup set to default.
[12:32:57.403] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:32:57.403] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:32:57.404] INFO: 4 hot pixels found in step 1
[12:32:58.392] INFO: Collecting data for 5 seconds...
[12:33:03.412] INFO: Done with hot pixel readout
[12:33:15.384] INFO: PixTest:: pg_setup set to default.
[12:33:15.385] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:33:15.385] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:33:15.385] INFO: 6 hot pixels found in step 2
[12:33:16.373] INFO: Collecting data for 5 seconds...
[12:33:21.392] INFO: Done with hot pixel readout
[12:33:33.348] INFO: PixTest:: pg_setup set to default.
[12:33:33.348] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:33:33.349] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:33:33.350] INFO: 5 hot pixels found in step 3
[12:33:34.337] INFO: Collecting data for 5 seconds...
[12:33:39.354] INFO: Done with hot pixel readout
[12:33:51.313] INFO: PixTest:: pg_setup set to default.
[12:33:51.313] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:33:51.314] INFO: 4 hot pixels found in step 4
[12:33:52.302] INFO: Collecting data for 5 seconds...
[12:33:57.320] INFO: Done with hot pixel readout
[12:34:09.145] INFO: PixTest:: pg_setup set to default.
[12:34:09.145] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:34:09.145] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:34:09.146] INFO: 6 hot pixels found in step 5
[12:34:10.134] INFO: Collecting data for 5 seconds...
[12:34:15.153] INFO: Done with hot pixel readout
[12:34:26.874] INFO: PixTest:: pg_setup set to default.
[12:34:26.874] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:34:26.874] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:34:26.875] INFO: 3 hot pixels found in step 6
[12:34:27.862] INFO: Collecting data for 5 seconds...
[12:34:32.881] INFO: Done with hot pixel readout
[12:34:44.522] INFO: PixTest:: pg_setup set to default.
[12:34:44.523] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:34:44.523] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:34:44.523] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:34:44.523] INFO: 5 hot pixels found in step 7
[12:34:45.511] INFO: Collecting data for 5 seconds...
[12:34:50.530] INFO: Done with hot pixel readout
[12:35:02.096] INFO: PixTest:: pg_setup set to default.
[12:35:02.096] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:35:02.096] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:35:02.097] INFO: 3 hot pixels found in step 8
[12:35:03.084] INFO: Collecting data for 5 seconds...
[12:35:08.101] INFO: Done with hot pixel readout
[12:35:19.723] INFO: PixTest:: pg_setup set to default.
[12:35:19.723] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:35:19.723] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:35:19.724] INFO: 3 hot pixels found in step 9
[12:35:20.712] INFO: Collecting data for 5 seconds...
[12:35:25.728] INFO: Done with hot pixel readout
[12:35:37.292] INFO: PixTest:: pg_setup set to default.
[12:35:37.292] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:35:37.292] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:35:37.293] INFO: 4 hot pixels found in step 10
[12:35:38.281] INFO: Collecting data for 5 seconds...
[12:35:43.298] INFO: Done with hot pixel readout
[12:35:54.835] INFO: PixTest:: pg_setup set to default.
[12:35:54.835] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:35:54.835] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:35:54.835] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:35:54.836] INFO: 5 hot pixels found in step 11
[12:35:55.824] INFO: Collecting data for 5 seconds...
[12:36:00.839] INFO: Done with hot pixel readout
[12:36:12.404] INFO: PixTest:: pg_setup set to default.
[12:36:12.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:36:12.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:36:12.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:36:12.405] INFO: 5 hot pixels found in step 12
[12:36:13.393] INFO: Collecting data for 5 seconds...
[12:36:18.410] INFO: Done with hot pixel readout
[12:36:29.980] INFO: PixTest:: pg_setup set to default.
[12:36:29.980] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:36:29.980] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:36:29.980] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:36:29.981] INFO: 6 hot pixels found in step 13
[12:36:30.969] INFO: Collecting data for 5 seconds...
[12:36:35.985] INFO: Done with hot pixel readout
[12:36:47.516] INFO: PixTest:: pg_setup set to default.
[12:36:47.516] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:36:47.516] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:36:47.516] INFO: 2 hot pixels found in step 14
[12:36:47.547] INFO: 2 hot pixels could not be trimmed and have been masked.
[12:36:47.551] INFO: PixTest::trimHotPixels() done
[12:36:47.570] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C0.dat
[12:36:47.576] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C1.dat
[12:36:47.582] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C2.dat
[12:36:47.588] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C3.dat
[12:36:47.593] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C4.dat
[12:36:47.599] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C5.dat
[12:36:47.604] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C6.dat
[12:36:47.610] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C7.dat
[12:36:47.615] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C8.dat
[12:36:47.621] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C9.dat
[12:36:47.626] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C10.dat
[12:36:47.632] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C11.dat
[12:36:47.637] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C12.dat
[12:36:47.643] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C13.dat
[12:36:47.662] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C14.dat
[12:36:47.677] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C15.dat
[12:36:47.683] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//defaultMaskFile.dat
[12:36:47.701] INFO: enter test to run
[12:37:41.833] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:37:41.833] INFO: running: highrate
[12:37:41.837] INFO: ----------------------------------------------------------------------
[12:37:41.837] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:37:41.837] INFO: ----------------------------------------------------------------------
[12:37:41.838] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:37:41.838] INFO: edge/corner pixel THR is adjusted
[12:37:41.838] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:37:42.799] INFO: Collecting data for 1 seconds...
[12:37:43.803] INFO: Done with hot pixel readout
[12:37:47.634] INFO: PixTest:: pg_setup set to default.
[12:37:47.634] INFO: 0 hot pixels found in step 0
[12:37:47.640] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:37:47.733] INFO: PixTest::trimHotPixels() done
[12:37:47.734] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C0.dat
[12:37:47.742] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C1.dat
[12:37:47.747] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C2.dat
[12:37:47.753] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C3.dat
[12:37:47.758] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C4.dat
[12:37:47.764] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C5.dat
[12:37:47.769] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C6.dat
[12:37:47.775] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C7.dat
[12:37:47.780] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C8.dat
[12:37:47.786] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C9.dat
[12:37:47.791] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C10.dat
[12:37:47.797] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C11.dat
[12:37:47.802] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C12.dat
[12:37:47.808] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C13.dat
[12:37:47.813] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C14.dat
[12:37:47.819] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//trimParameters35_C15.dat
[12:37:47.824] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-8-38_FPIXTest-17C-FNAL-160824-1317-300V_2016-08-24_13h17m_1472062643/000_FPIXTest_p17//defaultMaskFile.dat
[12:37:47.834] INFO: enter test to run
[12:38:11.080] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:38:11.081] INFO: running: xray
[12:38:11.082] INFO: ----------------------------------------------------------------------
[12:38:11.082] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:38:11.082] INFO: ----------------------------------------------------------------------
[12:38:11.085] INFO: ROC 0 masking hot pixel 1/7
[12:38:11.085] INFO: ROC 0 masking hot pixel 2/7
[12:38:11.085] INFO: ROC 0 masking pixel 1/7
[12:38:11.085] INFO: ROC 0 masking pixel 2/7
[12:38:12.063] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:38:23.735] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:38:53.156] INFO: Resuming triggers.
[12:39:04.831] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:39:34.183] INFO: Resuming triggers.
[12:39:45.856] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:39:45.929] WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!
[12:39:45.929] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[12:39:45.929] WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!
[12:39:45.929] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (4) != Token Chain Length (8)
[12:39:45.929] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:39:45.929] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c1 80c0 43c8 43c8 51a 222e 43c8 85b 2443 43c9 452 2241 43c8 43c8 43c9 50b 2046 43c8 e000 c000
[12:39:45.929] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bb 8040 4388 4388 4388 84c 220f 4388 4388 25d 284e 4388 419 2a2f 4388 4389 e000 c000
[12:39:45.929] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bc 80b1 4388 451 2a2e 74c 2441 4388 4388 65b 282d 4388 4388 120 2a2e 208 264f 45b 204d 4388 4388 55 222f 348 2a2b 4388 e000 c000
[12:39:45.929] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bd 80c0 4388 4388 4388 4388 4d0 2a40 4388 4388 253 226b 4388 753 264f 4388 e000 c000
[12:39:45.929] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== b0be 8000 43c8 57 2fff 2ff1 90 43ca 43ca 684 2044 43ca 43c8 43c8 92 282c e000 c000
[12:39:45.929] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bf 8040 43ca 2da 204f 414 2a68 43c8 43ca 43c8 43c8 255 2245 43c8 b 2061 43ca 43c8 e000 c000
[12:39:45.929] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c0 80b1 43c8 99 2240 43c8 43c8 43c8 43c9 4dd 2a4b 43c9 48c 2a4a 43c8 43c8 e000 c000
[12:39:45.929] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:39:45.929] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c1 80c0 43c8 43c8 43c9 43c8 499 262c 619 204e 74a 2266 43c9 10 244e 43c8 43c9 43c9 e000 c000
[12:39:45.929] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bb 8040 4388 15 266a 4388 4388 822 2069 4388 4388 4388 4388 4388 e000 c000
[12:39:45.929] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bc 80b1 4388 4388 18 244d 4388 4388 4388 4388 4388 4388 559 2660 559 2a4f e000 c000
[12:39:45.929] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bd 80c0 4388 9d 282a 48b 2066 4388 4388 4388 4388 4388 4388 4388 e000 c000
[12:39:45.929] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== b1be 8000 43c8 7d7 2401 578 43c8 340 2440 43ca 43ca 43c8 e000 c000
[12:39:45.929] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bf 8040 43ca 560 2a48 43ca 43c9 43ca 43ca 352 2648 43c9 43c9 43ca e000 c000
[12:39:45.929] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c0 80b1 43c9 43c9 41a 2848 413 246a 43c8 6c0 2428 43c9 43c9 43c9 43c9 43c9 e000 c000
[12:40:15.092] INFO: Resuming triggers.
[12:40:26.770] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[12:40:56.095] INFO: Resuming triggers.
[12:41:07.771] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[12:41:37.039] INFO: Resuming triggers.
[12:41:48.716] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[12:42:17.943] INFO: Resuming triggers.
[12:42:29.618] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[12:42:59.390] INFO: Resuming triggers.
[12:43:11.066] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[12:43:40.648] INFO: Resuming triggers.
[12:43:47.575] INFO: data taking finished, elapsed time: 100 seconds.
[12:44:05.473] INFO: PixTest:: pg_setup set to default.
[12:44:05.475] INFO: PixTestXray::doPhRun() done
[12:44:05.615] INFO: enter test to run
[12:44:29.374] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:44:29.374] INFO: running: xray
[12:44:29.375] INFO: ----------------------------------------------------------------------
[12:44:29.375] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:44:29.375] INFO: ----------------------------------------------------------------------
[12:44:29.380] INFO: ROC 0 masking hot pixel 1/7
[12:44:29.380] INFO: ROC 0 masking hot pixel 2/7
[12:44:29.380] INFO: ROC 0 masking pixel 1/7
[12:44:29.380] INFO: ROC 0 masking pixel 2/7
[12:44:30.341] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:44:37.327] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[12:45:07.941] INFO: Resuming triggers.
[12:45:14.928] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[12:45:45.638] INFO: Resuming triggers.
[12:45:52.621] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[12:46:23.859] INFO: Resuming triggers.
[12:46:30.843] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[12:47:01.949] INFO: Resuming triggers.
[12:47:08.934] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:47:39.069] INFO: Resuming triggers.
[12:47:46.051] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[12:48:16.148] INFO: Resuming triggers.
[12:48:23.128] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[12:48:53.304] INFO: Resuming triggers.
[12:49:00.290] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[12:49:30.475] INFO: Resuming triggers.
[12:49:37.458] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[12:50:07.600] INFO: Resuming triggers.
[12:50:14.590] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[12:50:14.836] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (7) != Token Chain Length (8)
[12:50:14.836] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:50:14.836] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a028 80b1 43c8 705 2668 43c8 43c8 450 2a42 43c8 43c8 30b 2865 43c8 43c8 4c8 262f 50c 2442 43c8 e000 c000
[12:50:14.836] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a022 8000 4388 11a 2082 68b 208c 4389 4389 313 2a40 54d 2246 4389 152 2463 713 2642 4388 308 2448 4c2 246e 4389 4389 d 2429 518 264f 4388 20a 2645 2da 2a4f e000 c000
[12:50:14.837] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a023 8040 4388 4388 4388 4388 151 204c 21b 246f 358 2848 4388 4388 a 284e 80 2645 34c 2a69 4ca 2a48 4388 4388 115 2224 41b 284f e000 c000
[12:50:14.837] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a024 80b1 4388 19 246a 4388 4388 4388 250 244a 289 2842 4388 50 2a48 4da 286c 4d1 2867 4388 691 2228 4388 4388 291 2845 4d8 266d 51c 2447 e000 c000
[12:50:14.837] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a025 80c0 4388 9b 284a 4388 18 2242 4388 71a 2642 4388 1 2042 4388 34c 2044 4388 4388 14c 2626 34b 3240 148a 2240 9f5 2ea8 f0 20b6 894 20a5 4c4 2139 e000 c000
[12:50:14.837] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a026 8000 43c8 43c8 43c8 418 2a45 749 264c 43c8 d 2042 43c8 418 2824 408 2648 849 224a 43c8 119 2668 43c8 60 284e 43c8 55a 2a4e e000 c000
[12:50:14.837] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a027 8040 43c8 5d 2884 61 2a4f 6d9 244f 43c8 40a 2445 43c8 160 2040 701 2045 43c8 258 2268 314 264b 43c8 6e2 226c 43c8 103 224e 159 284e 689 2448 721 2a62 43c8 48 2428 c4 204f 65c 2864 689 242d 43ca 14d 2a4f e000 c000
[12:50:14.837] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:50:14.837] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a128 80b1 43c8 14d 2665 43c8 405 2045 611 2485 759 222a 43c8 815 284c 43c8 813 2268 43c8 713 2463 43c8 43c8 6c3 2a4a 43c8 e000 c000
[12:50:14.837] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a122 8000 4388 75c 202e 4388 848 262f 4388 4389 12 226e 40b 2268 452 264a 6c3 2466 4388 4389 4388 4389 611 224c e000 c000
[12:50:14.837] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a123 8040 4388 109 2a68 351 2648 812 244e 854 266a 4389 a0 2062 253 2a4a 661 2264 4388 4389 4388 4d0 264f 4388 4388 519 264a 4389 e000 c000
[12:50:14.837] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a124 80b1 4388 4388 71d 280f 4388 9a 224e 4388 211 262e 54d 224f 850 2242 4388 560 204f 4388 4388 620 2a4e 4388 493 284c e000 c000
[12:50:14.837] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a125 80c0 4388 4388 759 2846 4388 4388 4388 2 226a 2cd 2a4f 30c 2a42 75a 244e 4388 45c 2066 4388 450 324b 53c8 e014 c0bf
[12:50:14.837] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a126 8000 43c8 70c 2864 43c8 1c 284a a 2a85 29b 264a 359 246a 43c8 160 264d 6c1 264c 43c8 10 2866 19 2268 a2 2266 243 2a62 43c8 51a 2a68 43c8 43c8 43c8 e000 c000
[12:50:14.837] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a127 8040 43c8 c9 2682 14c 2a67 43c8 848 2641 43c8 2e0 244c 305 2081 409 2042 43c8 621 204e 43c8 6ca 262a 43c8 43c8 25b 2883 43c8 e000 c000
[12:50:44.708] INFO: Resuming triggers.
[12:50:51.691] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[12:50:51.982] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (7) != Token Chain Length (8)
[12:50:51.982] WARNING: Channel 1 ROC 2: Readback start marker after 2 readouts!
[12:50:51.982] WARNING: Channel 1 ROC 3: Readback start marker after 1 readouts!
[12:50:51.982] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (4) != Token Chain Length (8)
[12:50:51.982] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:50:51.982] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e3 8040 43c8 43c8 43c8 34b 224f 43c8 6d8 2240 43c8 9b 2261 602 2a49 85a 224e 43c8 101 284f 28c 2626 43c8 19 2443 58 2a4a 21a 2a6b 491 242e 43c8 24c 2826 e000 c000
[12:50:51.982] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0dd 80c0 4388 150 2446 158 224e 254 2440 4388 4388 682 2a4e 4388 4388 4388 6a2 2628 4388 849 2640 4388 e000 c000
[12:50:51.982] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0de 8000 4388 25c 2248 438a 812 2a4f 4388 4d2 2642 438a 150 2625 319 2248 438a 680 2442 6cb 2865 438a 493 224f 4388 4c8 204e 4388 e000 c000
[12:50:51.982] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0df 8040 438a 4388 61d 2a48 438a 2d4 284f 4388 848 2240 4388 4388 438a 4388 e000 c000
[12:50:51.982] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e0 80b1 4388 294 2848 811 224e 4388 4388 804 2626 4388 6d1 264a 84d 2228 43c9 afa 2bef 43cb 2be3 5c3 39f9 b56 2bf9 fab 2fff 5fd 2dda e78 2ab0 114 289c 901 245c ca4 43c8 64c 2642 e000 c000
[12:50:51.982] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e1 80c0 43c8 21c 224e 49b 2468 558 2a6f 43c8 da 284c 11a 2a4f 43c8 43c9 43c8 43c8 21a 244e 498 204a 860 2246 43c9 95 2a4e 43c8 e000 c000
[12:50:51.982] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e2 8000 43c8 14 2a4f 43c9 43c9 814 262a 43c9 488 242f 741 286a 754 2a2a 43c8 612 2642 43c9 c 2a4e 59 2a82 145 2a44 548 2a4e 43c9 859 2240 43c8 4a 2a42 30a 2465 442 2040 e000 c000
[12:50:51.982] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:50:51.982] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1e3 8040 43c8 43c9 14c 2a66 70b 2422 70b 2a48 858 2a40 43c8 6c5 2245 43c9 709 2a46 43c8 43c8 43c8 43c9 705 282e e000 c000
[12:50:51.982] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1dd 80c0 4388 4388 420 204f 4388 4388 48d 2222 50c 2468 4388 6c8 262f 4388 14d 2443 4388 4388 e000 c000
[12:50:51.982] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1de 8000 4388 851 206e 4388 4a 204e 438a cc 2028 68d 2a4f 4388 455 2465 4388 21b 284f 60b 2a4f 71c 2246 438a 91 2649 438a 4388 e000 c000
[12:50:51.982] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1df 8040 438a 438a cc 2a45 4389 6c9 282f 438a 438a 6d1 262f 4389 4389 438a e000 c000
[12:50:51.982] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1e0 80b1 4389 292 24a2 4389 13 206c 91 2062 90 284a 680 2426 809 2a4f 43cb fed 2757 44d 2537 43cb 3541 1 2f53 575 2547 f6c 21e5 10e 2d59 71c 2042 43c9 845 222f e000 c000
[12:50:51.982] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1e1 80c0 43c8 655 2062 43c8 43c9 11 200f 651 2261 43c8 c8 246a 691 224b 43c9 43c8 41b 2266 43c9 43c9 e000 c000
[12:50:51.982] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1e2 8000 43c8 510 2a4f 43c8 84c 2446 43c8 209 224c 43c9 43c8 43c9 213 284a 2c3 204f 408 2844 45c 2a4a 43c8 43c9 e000 c000
[12:51:21.871] INFO: Resuming triggers.
[12:51:28.857] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[12:51:58.958] INFO: Resuming triggers.
[12:52:05.944] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[12:52:36.192] INFO: Resuming triggers.
[12:52:43.181] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[12:53:13.294] INFO: Resuming triggers.
[12:53:15.839] INFO: data taking finished, elapsed time: 100 seconds.
[12:53:27.099] INFO: PixTest:: pg_setup set to default.
[12:53:27.102] INFO: PixTestXray::doPhRun() done
[12:53:27.251] INFO: enter test to run
[12:54:37.298] INFO: test: HighRate no parameter change
[12:54:37.298] INFO: running: highrate
[12:54:37.299] INFO: ----------------------------------------------------------------------
[12:54:37.299] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:54:37.299] INFO: ----------------------------------------------------------------------
[12:54:37.439] INFO: Expecting 768 events.
[12:54:38.572] INFO: 768 events read in total (417ms).
[12:54:38.572] INFO: Test took 1267ms.
[12:54:38.577] INFO: ROC 0 masking pixel 1/7
[12:54:38.577] INFO: ROC 0 masking pixel 2/7
[12:54:39.376] INFO: Expecting 41600 events.
[12:54:42.465] INFO: 41600 events read in total (2563ms).
[12:54:42.466] INFO: Test took 3887ms.
[12:54:42.499] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:43.229] INFO: Expecting 41600 events.
[12:54:46.436] INFO: 41600 events read in total (2680ms).
[12:54:46.438] INFO: Test took 3922ms.
[12:54:46.471] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:47.193] INFO: Expecting 41600 events.
[12:54:50.402] INFO: 41600 events read in total (2682ms).
[12:54:50.403] INFO: Test took 3914ms.
[12:54:50.436] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:51.164] INFO: Expecting 41600 events.
[12:54:54.396] INFO: 41600 events read in total (2705ms).
[12:54:54.397] INFO: Test took 3944ms.
[12:54:54.430] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:55.158] INFO: Expecting 41600 events.
[12:54:58.394] INFO: 41600 events read in total (2709ms).
[12:54:58.395] INFO: Test took 3947ms.
[12:54:58.428] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:59.156] INFO: Expecting 41600 events.
[12:55:02.400] INFO: 41600 events read in total (2717ms).
[12:55:02.400] INFO: Test took 3955ms.
[12:55:02.434] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:03.159] INFO: Expecting 41600 events.
[12:55:06.382] INFO: 41600 events read in total (2696ms).
[12:55:06.383] INFO: Test took 3931ms.
[12:55:06.417] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:07.140] INFO: Expecting 41600 events.
[12:55:10.486] INFO: 41600 events read in total (2819ms).
[12:55:10.487] INFO: Test took 4053ms.
[12:55:10.522] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:11.241] INFO: Expecting 41600 events.
[12:55:14.582] INFO: 41600 events read in total (2814ms).
[12:55:14.583] INFO: Test took 4044ms.
[12:55:14.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:15.327] INFO: Expecting 41600 events.
[12:55:18.549] INFO: 41600 events read in total (2696ms).
[12:55:18.550] INFO: Test took 3916ms.
[12:55:18.585] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:19.300] INFO: Expecting 41600 events.
[12:55:22.501] INFO: 41600 events read in total (2674ms).
[12:55:22.502] INFO: Test took 3900ms.
[12:55:22.536] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:23.258] INFO: Expecting 41600 events.
[12:55:26.502] INFO: 41600 events read in total (2717ms).
[12:55:26.503] INFO: Test took 3949ms.
[12:55:26.536] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:27.257] INFO: Expecting 41600 events.
[12:55:30.383] INFO: 41600 events read in total (2599ms).
[12:55:30.384] INFO: Test took 3830ms.
[12:55:30.417] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:31.139] INFO: Expecting 41600 events.
[12:55:34.358] INFO: 41600 events read in total (2692ms).
[12:55:34.359] INFO: Test took 3925ms.
[12:55:34.393] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:35.116] INFO: Expecting 41600 events.
[12:55:38.446] INFO: 41600 events read in total (2803ms).
[12:55:38.447] INFO: Test took 4037ms.
[12:55:38.481] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:39.200] INFO: Expecting 41600 events.
[12:55:42.450] INFO: 41600 events read in total (2723ms).
[12:55:42.451] INFO: Test took 3952ms.
[12:55:42.485] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:43.209] INFO: Expecting 41600 events.
[12:55:46.486] INFO: 41600 events read in total (2750ms).
[12:55:46.487] INFO: Test took 3984ms.
[12:55:46.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:47.241] INFO: Expecting 41600 events.
[12:55:50.488] INFO: 41600 events read in total (2721ms).
[12:55:50.489] INFO: Test took 3951ms.
[12:55:50.524] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:51.245] INFO: Expecting 41600 events.
[12:55:54.489] INFO: 41600 events read in total (2717ms).
[12:55:54.490] INFO: Test took 3949ms.
[12:55:54.524] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:55.248] INFO: Expecting 41600 events.
[12:55:58.336] INFO: 41600 events read in total (2562ms).
[12:55:58.337] INFO: Test took 3794ms.
[12:55:58.370] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:58.737] INFO: enter test to run
[12:56:16.969] INFO: test: HighRate no parameter change
[12:56:16.969] INFO: running: highrate
[12:56:16.970] INFO: ----------------------------------------------------------------------
[12:56:16.970] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:56:16.970] INFO: ----------------------------------------------------------------------
[12:56:16.980] INFO: ROC 0 masking pixel 1/7
[12:56:16.980] INFO: ROC 0 masking pixel 2/7
[12:56:17.596] INFO: Expecting 208000 events.
[12:56:29.541] INFO: 208000 events read in total (11418ms).
[12:56:29.544] INFO: Test took 12564ms.
[12:56:29.692] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:29.945] INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 1 85 52 52 51 3 0 0 0
[12:56:29.945] INFO: number of red-efficiency pixels: 86 48 76 110 133 167 129 99 185 195 157 157 98 55 22 20
[12:56:29.945] INFO: number of X-ray hits detected: 66294 43249 63617 97826 102364 110321 108247 75468 86146 102005 94148 82890 86898 53760 21925 24827
[12:56:29.945] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:56:29.945] INFO: number of Vcal hits detected: 207815 207952 207924 207888 207860 207828 207869 207850 203649 205253 205292 205342 207625 207945 207978 207980
[12:56:29.945] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[12:56:29.945] INFO: Vcal hit overall efficiency (%): 99.9 100.0 100.0 99.9 99.9 99.9 99.9 99.9 97.9 98.7 98.7 98.7 99.8 100.0 100.0 100.0
[12:56:29.945] INFO: X-ray hit rate [MHz/cm2]: 19.4 12.7 18.6 28.7 30.0 32.3 31.7 22.1 25.3 29.9 27.6 24.3 25.5 15.8 6.4 7.3
[12:56:29.945] INFO: PixTestHighRate::doXPixelAlive() done
[12:56:29.993] INFO: PixTest:: pg_setup set to default.
[12:56:29.006] INFO: enter test to run
[12:56:58.289] INFO: test: HighRate no parameter change
[12:56:58.289] INFO: running: highrate
[12:56:58.290] INFO: ----------------------------------------------------------------------
[12:56:58.290] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:56:58.290] INFO: ----------------------------------------------------------------------
[12:56:58.298] INFO: ROC 0 masking pixel 1/7
[12:56:58.298] INFO: ROC 0 masking pixel 2/7
[12:56:58.909] INFO: Expecting 208000 events.
[12:57:12.444] INFO: 208000 events read in total (13009ms).
[12:57:12.449] INFO: Test took 14151ms.
[12:57:12.728] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:13.027] INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 1 85 52 52 51 4 0 0 0
[12:57:13.027] INFO: number of red-efficiency pixels: 234 133 220 272 385 473 388 307 366 444 377 389 308 193 47 59
[12:57:13.027] INFO: number of X-ray hits detected: 132476 87402 127756 195356 203564 219123 215905 151880 164072 202533 187770 166409 174009 106319 43630 49526
[12:57:13.027] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:57:13.027] INFO: number of Vcal hits detected: 207657 207861 207763 207716 207591 207485 207570 207618 203459 204981 205045 205087 207390 207796 207950 207940
[12:57:13.027] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[12:57:13.027] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.9 99.9 99.8 99.8 99.8 99.8 97.8 98.5 98.6 98.6 99.7 99.9 100.0 100.0
[12:57:13.028] INFO: X-ray hit rate [MHz/cm2]: 38.8 25.6 37.4 57.3 59.7 64.2 63.3 44.5 48.1 59.4 55.0 48.8 51.0 31.2 12.8 14.5
[12:57:13.028] INFO: PixTestHighRate::doXPixelAlive() done
[12:57:13.073] INFO: PixTest:: pg_setup set to default.
[12:57:13.087] INFO: enter test to run
[12:57:29.585] INFO: test: HighRate no parameter change
[12:57:29.585] INFO: running: highrate
[12:57:29.586] INFO: ----------------------------------------------------------------------
[12:57:29.586] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:57:29.586] INFO: ----------------------------------------------------------------------
[12:57:29.595] INFO: ROC 0 masking pixel 1/7
[12:57:29.595] INFO: ROC 0 masking pixel 2/7
[12:57:30.204] INFO: Expecting 208000 events.
[12:57:45.828] INFO: 208000 events read in total (15097ms).
[12:57:45.837] INFO: Test took 16242ms.
[12:57:46.276] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:46.624] INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 1 85 52 52 51 5 0 0 0
[12:57:46.624] INFO: number of red-efficiency pixels: 484 281 522 690 1047 1220 960 721 630 1003 749 807 730 523 83 82
[12:57:46.624] INFO: number of X-ray hits detected: 203100 134631 195434 299936 313059 337631 331765 233038 248447 311298 287891 254598 266501 164027 67985 76179
[12:57:46.624] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:57:46.624] INFO: number of Vcal hits detected: 207302 207689 207361 207209 206714 206409 206830 207080 203144 204240 204567 204546 206880 207304 207914 207916
[12:57:46.624] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.7 99.4 99.3 99.5 99.6 99.7 99.5 99.6 99.6 99.6 99.7 100.0 100.0
[12:57:46.624] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.7 99.6 99.4 99.2 99.4 99.6 97.7 98.2 98.3 98.3 99.5 99.7 100.0 100.0
[12:57:46.624] INFO: X-ray hit rate [MHz/cm2]: 59.5 39.5 57.3 87.9 91.8 99.0 97.2 68.3 72.8 91.2 84.4 74.6 78.1 48.1 19.9 22.3
[12:57:46.624] INFO: PixTestHighRate::doXPixelAlive() done
[12:57:46.670] INFO: PixTest:: pg_setup set to default.
[12:57:46.694] INFO: enter test to run
[12:57:53.721] INFO: test: exit no parameter change
[12:57:54.078] QUIET: Connection to board 33 closed.
[12:57:54.079] INFO: pXar: this is the end, my friend