[12:42:59.835] INFO: *** Welcome to pxar ***
[12:42:59.835] INFO: *** Today: 2016/06/15
[12:43:00.755] INFO: *** Version: v1.9.0-814-g7497
[12:43:00.755] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//dacParameters35_C15.dat
[12:43:00.801] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:43:00.801] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//defaultMaskFile.dat
[12:43:00.804] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C15.dat
[12:43:00.925] INFO: clk: 4
[12:43:00.925] INFO: ctr: 4
[12:43:00.925] INFO: sda: 19
[12:43:00.925] INFO: tin: 9
[12:43:00.925] INFO: level: 15
[12:43:00.925] INFO: triggerdelay: 0
[12:43:00.925] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:43:00.925] INFO: Log level: INFO
[12:43:00.942] QUIET: Connection to board DTB_WREKRL opened.
[12:43:00.945] 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:43:00.948] INFO: RPC call hashes of host and DTB match: 398089610
[12:43:02.496] INFO: DUT info:
[12:43:02.496] INFO: The DUT currently contains the following objects:
[12:43:02.496] INFO: 2 TBM Cores tbm08c (2 ON)
[12:43:02.496] INFO: TBM Core alpha (0): 7 registers set
[12:43:02.496] INFO: TBM Core beta (1): 7 registers set
[12:43:02.496] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:43:02.496] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.496] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.496] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.496] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.496] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.496] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.496] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.496] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.496] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.496] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.496] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.496] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.497] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.497] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.497] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.497] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:02.944] INFO: enter 'restricted' command line mode
[12:43:02.944] INFO: enter test to run
[12:43:06.854] INFO: test: PixelAlive no parameter change
[12:43:06.854] INFO: running: pixelalive
[12:43:06.923] INFO: ----------------------------------------------------------------------
[12:43:06.923] 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:43:06.923] INFO: ----------------------------------------------------------------------
[12:43:07.242] INFO: Expecting 41600 events.
[12:43:11.581] INFO: 41600 events read in total (3621ms).
[12:43:11.747] INFO: Test took 4820ms.
[12:43:11.761] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:12.352] INFO: PixTestAlive::aliveTest() done
[12:43:12.352] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 3 0 0 0 0 0 0
[12:43:12.496] INFO: enter test to run
[12:43:28.142] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:43:28.142] INFO: running: highrate
[12:43:28.151] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:43:28.396] INFO: ----------------------------------------------------------------------
[12:43:28.396] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:43:28.396] INFO: ----------------------------------------------------------------------
[12:43:28.396] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:43:28.396] INFO: edge/corner pixel THR is adjusted
[12:43:28.396] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:43:29.355] INFO: Collecting data for 5 seconds...
[12:43:34.371] INFO: Done with hot pixel readout
[12:43:46.118] INFO: PixTest:: pg_setup set to default.
[12:43:46.119] INFO: 12 hot pixels found in step 0
[12:43:47.109] INFO: Collecting data for 5 seconds...
[12:43:52.125] INFO: Done with hot pixel readout
[12:44:03.895] INFO: PixTest:: pg_setup set to default.
[12:44:03.896] INFO: 14 hot pixels found in step 1
[12:44:04.890] INFO: Collecting data for 5 seconds...
[12:44:09.906] INFO: Done with hot pixel readout
[12:44:21.726] INFO: PixTest:: pg_setup set to default.
[12:44:21.726] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:44:21.726] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:44:21.727] INFO: 15 hot pixels found in step 2
[12:44:22.719] INFO: Collecting data for 5 seconds...
[12:44:27.735] INFO: Done with hot pixel readout
[12:44:39.474] INFO: PixTest:: pg_setup set to default.
[12:44:39.475] INFO: 12 hot pixels found in step 3
[12:44:40.467] INFO: Collecting data for 5 seconds...
[12:44:45.483] INFO: Done with hot pixel readout
[12:44:57.269] INFO: PixTest:: pg_setup set to default.
[12:44:57.270] INFO: 12 hot pixels found in step 4
[12:44:58.262] INFO: Collecting data for 5 seconds...
[12:45:03.278] INFO: Done with hot pixel readout
[12:45:15.070] INFO: PixTest:: pg_setup set to default.
[12:45:15.071] INFO: 9 hot pixels found in step 5
[12:45:16.062] INFO: Collecting data for 5 seconds...
[12:45:21.078] INFO: Done with hot pixel readout
[12:45:32.872] INFO: PixTest:: pg_setup set to default.
[12:45:32.872] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:45:32.872] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:45:32.872] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:45:32.873] INFO: 8 hot pixels found in step 6
[12:45:33.864] INFO: Collecting data for 5 seconds...
[12:45:38.879] INFO: Done with hot pixel readout
[12:45:50.676] INFO: PixTest:: pg_setup set to default.
[12:45:50.676] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:45:50.676] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:45:50.676] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:45:50.676] INFO: 10 hot pixels found in step 7
[12:45:51.668] INFO: Collecting data for 5 seconds...
[12:45:56.684] INFO: Done with hot pixel readout
[12:46:08.436] INFO: PixTest:: pg_setup set to default.
[12:46:08.436] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:46:08.436] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:46:08.436] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:46:08.437] INFO: 9 hot pixels found in step 8
[12:46:09.429] INFO: Collecting data for 5 seconds...
[12:46:14.445] INFO: Done with hot pixel readout
[12:46:26.245] INFO: PixTest:: pg_setup set to default.
[12:46:26.245] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:46:26.245] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:46:26.245] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:46:26.245] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:46:26.246] INFO: 15 hot pixels found in step 9
[12:46:27.237] INFO: Collecting data for 5 seconds...
[12:46:32.254] INFO: Done with hot pixel readout
[12:46:44.023] INFO: PixTest:: pg_setup set to default.
[12:46:44.023] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:46:44.023] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:46:44.023] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:46:44.024] INFO: 5 hot pixels found in step 10
[12:46:45.015] INFO: Collecting data for 5 seconds...
[12:46:50.031] INFO: Done with hot pixel readout
[12:47:01.758] INFO: PixTest:: pg_setup set to default.
[12:47:01.759] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:47:01.759] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:47:01.759] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:47:01.759] INFO: 7 hot pixels found in step 11
[12:47:02.750] INFO: Collecting data for 5 seconds...
[12:47:07.765] INFO: Done with hot pixel readout
[12:47:19.531] INFO: PixTest:: pg_setup set to default.
[12:47:19.531] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:47:19.531] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:47:19.531] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:47:19.531] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:47:19.532] INFO: 5 hot pixels found in step 12
[12:47:20.524] INFO: Collecting data for 5 seconds...
[12:47:25.539] INFO: Done with hot pixel readout
[12:47:37.282] INFO: PixTest:: pg_setup set to default.
[12:47:37.282] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:47:37.282] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:47:37.282] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:47:37.282] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:47:37.283] INFO: 7 hot pixels found in step 13
[12:47:38.274] INFO: Collecting data for 5 seconds...
[12:47:43.290] INFO: Done with hot pixel readout
[12:47:55.072] INFO: PixTest:: pg_setup set to default.
[12:47:55.072] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:47:55.072] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:47:55.073] INFO: 10 hot pixels found in step 14
[12:47:55.108] INFO: 10 hot pixels could not be trimmed and have been masked.
[12:47:55.123] INFO: PixTest::trimHotPixels() done
[12:47:55.123] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C0.dat
[12:47:55.132] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C1.dat
[12:47:55.137] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C2.dat
[12:47:55.142] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C3.dat
[12:47:55.148] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C4.dat
[12:47:55.153] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C5.dat
[12:47:55.158] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C6.dat
[12:47:55.163] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C7.dat
[12:47:55.168] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C8.dat
[12:47:55.173] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C9.dat
[12:47:55.178] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C10.dat
[12:47:55.183] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C11.dat
[12:47:55.189] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C12.dat
[12:47:55.194] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C13.dat
[12:47:55.199] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C14.dat
[12:47:55.204] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C15.dat
[12:47:55.209] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//defaultMaskFile.dat
[12:47:55.219] INFO: enter test to run
[12:48:37.596] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:48:37.596] INFO: running: highrate
[12:48:37.600] INFO: ----------------------------------------------------------------------
[12:48:37.600] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:48:37.600] INFO: ----------------------------------------------------------------------
[12:48:37.600] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:48:37.600] INFO: edge/corner pixel THR is adjusted
[12:48:37.600] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:48:38.560] INFO: Collecting data for 1 seconds...
[12:48:39.563] INFO: Done with hot pixel readout
[12:48:43.525] INFO: PixTest:: pg_setup set to default.
[12:48:43.526] INFO: 0 hot pixels found in step 0
[12:48:43.531] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:48:43.627] INFO: PixTest::trimHotPixels() done
[12:48:43.627] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C0.dat
[12:48:43.636] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C1.dat
[12:48:43.641] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C2.dat
[12:48:43.646] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C3.dat
[12:48:43.652] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C4.dat
[12:48:43.657] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C5.dat
[12:48:43.662] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C6.dat
[12:48:43.667] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C7.dat
[12:48:43.673] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C8.dat
[12:48:43.678] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C9.dat
[12:48:43.683] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C10.dat
[12:48:43.689] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C11.dat
[12:48:43.694] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C12.dat
[12:48:43.699] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C13.dat
[12:48:43.717] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C14.dat
[12:48:43.726] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C15.dat
[12:48:43.734] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-K-3-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//defaultMaskFile.dat
[12:48:43.743] INFO: enter test to run
[12:50:19.779] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:50:19.779] INFO: running: xray
[12:50:19.780] INFO: ----------------------------------------------------------------------
[12:50:19.780] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:50:19.780] INFO: ----------------------------------------------------------------------
[12:50:20.765] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:50:32.237] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:51:01.556] INFO: Resuming triggers.
[12:51:13.029] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[12:51:42.566] INFO: Resuming triggers.
[12:51:54.036] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:52:23.453] INFO: Resuming triggers.
[12:52:34.925] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[12:53:04.278] INFO: Resuming triggers.
[12:53:15.749] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[12:53:45.191] INFO: Resuming triggers.
[12:53:56.664] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[12:54:26.028] INFO: Resuming triggers.
[12:54:37.499] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[12:55:06.917] INFO: Resuming triggers.
[12:55:18.391] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[12:55:47.834] INFO: Resuming triggers.
[12:55:56.376] INFO: data taking finished, elapsed time: 100 seconds.
[12:56:18.418] INFO: PixTest:: pg_setup set to default.
[12:56:18.421] INFO: PixTestXray::doPhRun() done
[12:56:18.589] INFO: enter test to run
[12:56:55.216] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:56:55.216] INFO: running: xray
[12:56:55.217] INFO: ----------------------------------------------------------------------
[12:56:55.218] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:56:55.218] INFO: ----------------------------------------------------------------------
[12:56:56.181] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:57:02.953] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[12:57:33.058] INFO: Resuming triggers.
[12:57:39.832] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[12:58:09.967] INFO: Resuming triggers.
[12:58:16.739] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[12:58:46.863] INFO: Resuming triggers.
[12:58:53.637] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[12:59:23.739] INFO: Resuming triggers.
[12:59:30.513] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[13:00:00.657] INFO: Resuming triggers.
[13:00:07.428] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[13:00:37.528] INFO: Resuming triggers.
[13:00:44.299] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[13:01:14.448] INFO: Resuming triggers.
[13:01:21.218] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[13:01:51.347] INFO: Resuming triggers.
[13:01:58.119] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[13:02:28.188] INFO: Resuming triggers.
[13:02:34.956] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[13:03:05.035] INFO: Resuming triggers.
[13:03:11.804] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[13:03:41.879] INFO: Resuming triggers.
[13:03:48.646] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[13:04:18.831] INFO: Resuming triggers.
[13:04:25.601] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[13:04:55.713] INFO: Resuming triggers.
[13:05:02.484] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[13:05:32.464] INFO: Resuming triggers.
[13:05:37.982] INFO: data taking finished, elapsed time: 100 seconds.
[13:06:02.731] INFO: PixTest:: pg_setup set to default.
[13:06:02.735] INFO: PixTestXray::doPhRun() done
[13:06:02.882] INFO: enter test to run
[13:16:22.624] INFO: test: HighRate no parameter change
[13:16:22.624] INFO: running: highrate
[13:16:22.625] INFO: ----------------------------------------------------------------------
[13:16:22.625] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:16:22.625] INFO: ----------------------------------------------------------------------
[13:16:22.768] INFO: Expecting 768 events.
[13:16:23.902] INFO: 768 events read in total (418ms).
[13:16:23.903] INFO: Test took 1269ms.
[13:16:24.707] INFO: Expecting 41600 events.
[13:16:27.737] INFO: 41600 events read in total (2503ms).
[13:16:27.738] INFO: Test took 3829ms.
[13:16:27.770] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:28.499] INFO: Expecting 41600 events.
[13:16:31.677] INFO: 41600 events read in total (2651ms).
[13:16:31.678] INFO: Test took 3890ms.
[13:16:31.712] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:32.435] INFO: Expecting 41600 events.
[13:16:35.663] INFO: 41600 events read in total (2701ms).
[13:16:35.664] INFO: Test took 3934ms.
[13:16:35.698] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:36.423] INFO: Expecting 41600 events.
[13:16:39.650] INFO: 41600 events read in total (2700ms).
[13:16:39.651] INFO: Test took 3934ms.
[13:16:39.684] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:40.411] INFO: Expecting 41600 events.
[13:16:43.634] INFO: 41600 events read in total (2696ms).
[13:16:43.635] INFO: Test took 3933ms.
[13:16:43.669] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:44.395] INFO: Expecting 41600 events.
[13:16:47.627] INFO: 41600 events read in total (2705ms).
[13:16:47.628] INFO: Test took 3940ms.
[13:16:47.662] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:48.386] INFO: Expecting 41600 events.
[13:16:51.624] INFO: 41600 events read in total (2711ms).
[13:16:51.624] INFO: Test took 3944ms.
[13:16:51.658] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:52.382] INFO: Expecting 41600 events.
[13:16:55.617] INFO: 41600 events read in total (2708ms).
[13:16:55.618] INFO: Test took 3942ms.
[13:16:55.652] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:56.373] INFO: Expecting 41600 events.
[13:16:59.616] INFO: 41600 events read in total (2716ms).
[13:16:59.617] INFO: Test took 3946ms.
[13:16:59.651] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:00.375] INFO: Expecting 41600 events.
[13:17:03.615] INFO: 41600 events read in total (2713ms).
[13:17:03.616] INFO: Test took 3947ms.
[13:17:03.649] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:04.376] INFO: Expecting 41600 events.
[13:17:07.609] INFO: 41600 events read in total (2706ms).
[13:17:07.611] INFO: Test took 3943ms.
[13:17:07.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:08.370] INFO: Expecting 41600 events.
[13:17:11.616] INFO: 41600 events read in total (2719ms).
[13:17:11.617] INFO: Test took 3953ms.
[13:17:11.652] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:12.375] INFO: Expecting 41600 events.
[13:17:15.611] INFO: 41600 events read in total (2709ms).
[13:17:15.612] INFO: Test took 3943ms.
[13:17:15.646] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:16.370] INFO: Expecting 41600 events.
[13:17:19.610] INFO: 41600 events read in total (2713ms).
[13:17:19.611] INFO: Test took 3947ms.
[13:17:19.644] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:20.371] INFO: Expecting 41600 events.
[13:17:23.603] INFO: 41600 events read in total (2705ms).
[13:17:23.604] INFO: Test took 3941ms.
[13:17:23.638] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:24.362] INFO: Expecting 41600 events.
[13:17:27.603] INFO: 41600 events read in total (2714ms).
[13:17:27.603] INFO: Test took 3947ms.
[13:17:27.638] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:28.364] INFO: Expecting 41600 events.
[13:17:31.599] INFO: 41600 events read in total (2708ms).
[13:17:31.600] INFO: Test took 3944ms.
[13:17:31.633] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:32.359] INFO: Expecting 41600 events.
[13:17:35.591] INFO: 41600 events read in total (2705ms).
[13:17:35.592] INFO: Test took 3940ms.
[13:17:35.626] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:36.349] INFO: Expecting 41600 events.
[13:17:39.577] INFO: 41600 events read in total (2701ms).
[13:17:39.578] INFO: Test took 3933ms.
[13:17:39.612] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:40.338] INFO: Expecting 41600 events.
[13:17:43.435] INFO: 41600 events read in total (2570ms).
[13:17:43.436] INFO: Test took 3805ms.
[13:17:43.470] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:43.827] INFO: enter test to run
[13:19:38.463] INFO: test: HighRate no parameter change
[13:19:38.463] INFO: running: highrate
[13:19:38.464] INFO: ----------------------------------------------------------------------
[13:19:38.464] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:19:38.464] INFO: ----------------------------------------------------------------------
[13:19:39.079] INFO: Expecting 208000 events.
[13:19:50.886] INFO: 208000 events read in total (11280ms).
[13:19:50.889] INFO: Test took 12415ms.
[13:19:51.032] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:51.285] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 3 0 0 0 0 0 0
[13:19:51.285] INFO: number of red-efficiency pixels: 64 36 72 134 185 128 150 73 93 104 95 91 112 60 38 36
[13:19:51.285] INFO: number of X-ray hits detected: 62593 43361 68816 108849 115735 119449 115202 86404 75723 94121 91681 79105 81836 48424 23155 28691
[13:19:51.285] 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:19:51.285] INFO: number of Vcal hits detected: 207935 207964 207920 207864 207800 207871 207845 207925 207905 207747 207904 207907 207888 207940 207962 207964
[13:19:51.285] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[13:19:51.285] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 100.0 100.0 99.9 100.0 100.0 100.0
[13:19:51.285] INFO: X-ray hit rate [MHz/cm2]: 18.3 12.7 20.2 31.9 33.9 35.0 33.8 25.3 22.2 27.6 26.9 23.2 24.0 14.2 6.8 8.4
[13:19:51.285] INFO: PixTestHighRate::doXPixelAlive() done
[13:19:51.336] INFO: PixTest:: pg_setup set to default.
[13:19:51.351] INFO: enter test to run
[13:21:21.366] INFO: test: HighRate no parameter change
[13:21:21.366] INFO: running: highrate
[13:21:21.368] INFO: ----------------------------------------------------------------------
[13:21:21.368] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:21:21.368] INFO: ----------------------------------------------------------------------
[13:21:21.986] INFO: Expecting 208000 events.
[13:21:35.502] INFO: 208000 events read in total (12989ms).
[13:21:35.507] INFO: Test took 14129ms.
[13:21:35.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:36.092] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 3 0 0 0 0 0 0
[13:21:36.092] INFO: number of red-efficiency pixels: 233 103 207 450 584 403 386 257 267 361 305 228 281 144 52 68
[13:21:36.092] INFO: number of X-ray hits detected: 127293 87878 139351 220212 234080 241204 232268 175888 154164 192320 185275 161084 164836 97529 46886 58046
[13:21:36.092] 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:21:36.092] INFO: number of Vcal hits detected: 207751 207891 207771 207517 207335 207566 207583 207726 207721 207454 207679 207760 207704 207848 207946 207931
[13:21:36.092] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.7 99.8 99.8 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[13:21:36.092] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.8 99.9 99.9 99.7 99.8 99.9 99.9 99.9 100.0 100.0
[13:21:36.092] INFO: X-ray hit rate [MHz/cm2]: 37.3 25.8 40.8 64.5 68.6 70.7 68.1 51.6 45.2 56.4 54.3 47.2 48.3 28.6 13.7 17.0
[13:21:36.093] INFO: PixTestHighRate::doXPixelAlive() done
[13:21:36.145] INFO: PixTest:: pg_setup set to default.
[13:21:36.161] INFO: enter test to run
[13:21:50.078] INFO: test: HighRate no parameter change
[13:21:50.078] INFO: running: highrate
[13:21:50.079] INFO: ----------------------------------------------------------------------
[13:21:50.079] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:21:50.079] INFO: ----------------------------------------------------------------------
[13:21:50.696] INFO: Expecting 208000 events.
[13:22:05.997] INFO: 208000 events read in total (14774ms).
[13:22:06.004] INFO: Test took 15914ms.
[13:22:06.429] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:06.777] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 3 0 0 0 0 0 0
[13:22:06.777] INFO: number of red-efficiency pixels: 443 195 495 1089 1322 993 913 613 531 799 572 421 621 349 99 137
[13:22:06.777] INFO: number of X-ray hits detected: 193616 133747 213413 333223 354724 366458 353111 266621 232641 292091 283517 245370 249838 148739 72012 88658
[13:22:06.777] 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:06.778] INFO: number of Vcal hits detected: 207485 207793 207385 206659 206232 206780 206895 207257 207404 206929 207370 207542 207293 207597 207896 207860
[13:22:06.778] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.4 99.2 99.5 99.5 99.7 99.7 99.6 99.7 99.8 99.7 99.8 100.0 99.9
[13:22:06.778] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.4 99.2 99.4 99.5 99.6 99.7 99.5 99.7 99.8 99.7 99.8 100.0 99.9
[13:22:06.778] INFO: X-ray hit rate [MHz/cm2]: 56.8 39.2 62.6 97.7 104.0 107.4 103.5 78.1 68.2 85.6 83.1 71.9 73.2 43.6 21.1 26.0
[13:22:06.778] INFO: PixTestHighRate::doXPixelAlive() done
[13:22:06.830] INFO: PixTest:: pg_setup set to default.
[13:22:06.851] INFO: enter test to run
[13:22:20.390] INFO: test: exit no parameter change
[13:22:20.696] QUIET: Connection to board 33 closed.
[13:22:20.698] INFO: pXar: this is the end, my friend