[12:27:35.192] INFO: *** Welcome to pxar ***
[12:27:35.192] INFO: *** Today: 2016/07/01
[12:27:36.182] INFO: *** Version: v1.9.0-814-g7497
[12:27:36.182] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//dacParameters35_C15.dat
[12:27:36.264] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:27:36.265] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//defaultMaskFile.dat
[12:27:36.273] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C15.dat
[12:27:36.418] INFO: clk: 4
[12:27:36.419] INFO: ctr: 4
[12:27:36.419] INFO: sda: 19
[12:27:36.419] INFO: tin: 9
[12:27:36.419] INFO: level: 15
[12:27:36.419] INFO: triggerdelay: 0
[12:27:36.419] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:27:36.419] INFO: Log level: INFO
[12:27:36.437] QUIET: Connection to board DTB_WREKRL opened.
[12:27:36.440] 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:27:36.443] INFO: RPC call hashes of host and DTB match: 398089610
[12:27:37.989] INFO: DUT info:
[12:27:37.989] INFO: The DUT currently contains the following objects:
[12:27:37.989] INFO: 2 TBM Cores tbm08c (2 ON)
[12:27:37.989] INFO: TBM Core alpha (0): 7 registers set
[12:27:37.989] INFO: TBM Core beta (1): 7 registers set
[12:27:37.989] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:27:37.989] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:37.989] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:37.989] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:37.989] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:37.989] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:37.989] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:37.989] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:37.989] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:37.989] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:37.989] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:37.990] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:37.990] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:37.990] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:37.990] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:37.990] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:37.990] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:38.421] INFO: enter 'restricted' command line mode
[12:27:38.421] INFO: enter test to run
[12:27:39.960] INFO: test: PixelAlive no parameter change
[12:27:39.960] INFO: running: pixelalive
[12:27:40.033] INFO: ----------------------------------------------------------------------
[12:27:40.033] 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:27:40.033] INFO: ----------------------------------------------------------------------
[12:27:40.359] INFO: Expecting 41600 events.
[12:27:44.693] INFO: 41600 events read in total (3616ms).
[12:27:44.863] INFO: Test took 4826ms.
[12:27:44.873] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:45.403] INFO: PixTestAlive::aliveTest() done
[12:27:45.403] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 2 0 0 0 1 1 0 0 0
[12:27:45.648] INFO: enter test to run
[12:28:57.599] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:28:57.599] INFO: running: highrate
[12:28:57.610] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:28:57.867] INFO: ----------------------------------------------------------------------
[12:28:57.867] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:28:57.867] INFO: ----------------------------------------------------------------------
[12:28:57.867] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:28:57.867] INFO: edge/corner pixel THR is adjusted
[12:28:57.867] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:28:58.826] INFO: Collecting data for 5 seconds...
[12:29:03.842] INFO: Done with hot pixel readout
[12:29:15.529] INFO: PixTest:: pg_setup set to default.
[12:29:15.530] INFO: 22 hot pixels found in step 0
[12:29:16.521] INFO: Collecting data for 5 seconds...
[12:29:21.537] INFO: Done with hot pixel readout
[12:29:33.156] INFO: PixTest:: pg_setup set to default.
[12:29:33.157] INFO: 24 hot pixels found in step 1
[12:29:34.148] INFO: Collecting data for 5 seconds...
[12:29:39.165] INFO: Done with hot pixel readout
[12:29:50.858] INFO: PixTest:: pg_setup set to default.
[12:29:50.859] INFO: 23 hot pixels found in step 2
[12:29:51.851] INFO: Collecting data for 5 seconds...
[12:29:56.868] INFO: Done with hot pixel readout
[12:30:08.437] INFO: PixTest:: pg_setup set to default.
[12:30:08.438] INFO: 21 hot pixels found in step 3
[12:30:09.430] INFO: Collecting data for 5 seconds...
[12:30:14.447] INFO: Done with hot pixel readout
[12:30:26.116] INFO: PixTest:: pg_setup set to default.
[12:30:26.117] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:30:26.117] INFO: 20 hot pixels found in step 4
[12:30:27.109] INFO: Collecting data for 5 seconds...
[12:30:32.126] INFO: Done with hot pixel readout
[12:30:43.753] INFO: PixTest:: pg_setup set to default.
[12:30:43.754] INFO: 21 hot pixels found in step 5
[12:30:44.746] INFO: Collecting data for 5 seconds...
[12:30:49.762] INFO: Done with hot pixel readout
[12:31:01.439] INFO: PixTest:: pg_setup set to default.
[12:31:01.440] INFO: 17 hot pixels found in step 6
[12:31:02.432] INFO: Collecting data for 5 seconds...
[12:31:07.449] INFO: Done with hot pixel readout
[12:31:19.065] INFO: PixTest:: pg_setup set to default.
[12:31:19.065] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:31:19.065] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:31:19.066] INFO: 17 hot pixels found in step 7
[12:31:20.058] INFO: Collecting data for 5 seconds...
[12:31:25.075] INFO: Done with hot pixel readout
[12:31:36.444] INFO: PixTest:: pg_setup set to default.
[12:31:36.444] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:31:36.445] INFO: 13 hot pixels found in step 8
[12:31:37.438] INFO: Collecting data for 5 seconds...
[12:31:42.454] INFO: Done with hot pixel readout
[12:31:54.038] INFO: PixTest:: pg_setup set to default.
[12:31:54.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:31:54.039] INFO: 16 hot pixels found in step 9
[12:31:55.031] INFO: Collecting data for 5 seconds...
[12:32:00.047] INFO: Done with hot pixel readout
[12:32:11.657] INFO: PixTest:: pg_setup set to default.
[12:32:11.657] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:32:11.658] INFO: 14 hot pixels found in step 10
[12:32:12.650] INFO: Collecting data for 5 seconds...
[12:32:17.667] INFO: Done with hot pixel readout
[12:32:29.254] INFO: PixTest:: pg_setup set to default.
[12:32:29.254] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:32:29.255] INFO: 11 hot pixels found in step 11
[12:32:30.247] INFO: Collecting data for 5 seconds...
[12:32:35.263] INFO: Done with hot pixel readout
[12:32:46.879] INFO: PixTest:: pg_setup set to default.
[12:32:46.879] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:32:46.880] INFO: 17 hot pixels found in step 12
[12:32:47.873] INFO: Collecting data for 5 seconds...
[12:32:52.889] INFO: Done with hot pixel readout
[12:33:04.551] INFO: PixTest:: pg_setup set to default.
[12:33:04.551] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:33:04.552] INFO: 9 hot pixels found in step 13
[12:33:05.545] INFO: Collecting data for 5 seconds...
[12:33:10.561] INFO: Done with hot pixel readout
[12:33:22.125] INFO: PixTest:: pg_setup set to default.
[12:33:22.126] INFO: 11 hot pixels found in step 14
[12:33:22.163] INFO: 11 hot pixels could not be trimmed and have been masked.
[12:33:22.174] INFO: PixTest::trimHotPixels() done
[12:33:22.174] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C0.dat
[12:33:22.183] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C1.dat
[12:33:22.189] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C2.dat
[12:33:22.194] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C3.dat
[12:33:22.199] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C4.dat
[12:33:22.204] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C5.dat
[12:33:22.210] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C6.dat
[12:33:22.215] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C7.dat
[12:33:22.220] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C8.dat
[12:33:22.225] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C9.dat
[12:33:22.231] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C10.dat
[12:33:22.236] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C11.dat
[12:33:22.241] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C12.dat
[12:33:22.246] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C13.dat
[12:33:22.252] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C14.dat
[12:33:22.257] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C15.dat
[12:33:22.262] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//defaultMaskFile.dat
[12:33:22.272] INFO: enter test to run
[12:34:21.917] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:34:21.917] INFO: running: highrate
[12:34:21.922] INFO: ----------------------------------------------------------------------
[12:34:21.922] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:34:21.922] INFO: ----------------------------------------------------------------------
[12:34:21.922] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:34:21.922] INFO: edge/corner pixel THR is adjusted
[12:34:21.922] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:34:22.879] INFO: Collecting data for 1 seconds...
[12:34:23.883] INFO: Done with hot pixel readout
[12:34:27.793] INFO: PixTest:: pg_setup set to default.
[12:34:27.794] INFO: 0 hot pixels found in step 0
[12:34:27.799] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:34:27.896] INFO: PixTest::trimHotPixels() done
[12:34:27.896] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C0.dat
[12:34:27.909] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C1.dat
[12:34:27.917] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C2.dat
[12:34:27.922] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C3.dat
[12:34:27.928] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C4.dat
[12:34:27.933] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C5.dat
[12:34:27.938] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C6.dat
[12:34:27.944] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C7.dat
[12:34:27.949] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C8.dat
[12:34:27.954] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C9.dat
[12:34:27.960] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C10.dat
[12:34:27.965] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C11.dat
[12:34:27.971] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C12.dat
[12:34:27.976] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C13.dat
[12:34:27.981] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C14.dat
[12:34:27.987] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//trimParameters35_C15.dat
[12:34:27.992] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-3-16_FPIXTest-17C-FNAL-160628-1356_2016-06-28_13h56m_1467140179/000_FPIXTest_p17//defaultMaskFile.dat
[12:34:28.002] INFO: enter test to run
[12:36:42.852] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:36:42.852] INFO: running: xray
[12:36:42.853] INFO: ----------------------------------------------------------------------
[12:36:42.853] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:36:42.853] INFO: ----------------------------------------------------------------------
[12:36:43.831] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:36:55.430] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:37:24.821] INFO: Resuming triggers.
[12:37:36.422] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:38:06.510] INFO: Resuming triggers.
[12:38:18.111] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:38:48.494] INFO: Resuming triggers.
[12:39:00.099] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[12:39:29.926] INFO: Resuming triggers.
[12:39:41.528] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[12:40:10.976] INFO: Resuming triggers.
[12:40:22.583] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[12:40:52.026] INFO: Resuming triggers.
[12:41:03.631] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[12:41:33.155] INFO: Resuming triggers.
[12:41:44.752] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[12:42:14.371] INFO: Resuming triggers.
[12:42:21.903] INFO: data taking finished, elapsed time: 100 seconds.
[12:42:41.239] INFO: PixTest:: pg_setup set to default.
[12:42:41.242] INFO: PixTestXray::doPhRun() done
[12:42:41.411] INFO: enter test to run
[12:44:09.689] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:44:09.689] INFO: running: xray
[12:44:09.690] INFO: ----------------------------------------------------------------------
[12:44:09.690] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:44:09.690] INFO: ----------------------------------------------------------------------
[12:44:10.655] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:44:17.488] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[12:44:47.735] INFO: Resuming triggers.
[12:44:54.562] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[12:45:24.750] INFO: Resuming triggers.
[12:45:31.578] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[12:46:01.951] INFO: Resuming triggers.
[12:46:08.781] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[12:46:38.666] INFO: Resuming triggers.
[12:46:45.495] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:47:14.912] INFO: Resuming triggers.
[12:47:21.744] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[12:47:52.052] INFO: Resuming triggers.
[12:47:58.883] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[12:48:29.549] INFO: Resuming triggers.
[12:48:36.384] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[12:49:07.292] INFO: Resuming triggers.
[12:49:14.125] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[12:49:45.057] INFO: Resuming triggers.
[12:49:51.889] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[12:50:23.011] INFO: Resuming triggers.
[12:50:29.845] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[12:51:00.682] INFO: Resuming triggers.
[12:51:07.521] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[12:51:37.777] INFO: Resuming triggers.
[12:51:44.607] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[12:52:14.821] INFO: Resuming triggers.
[12:52:21.657] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[12:52:51.880] INFO: Resuming triggers.
[12:52:56.576] INFO: data taking finished, elapsed time: 100 seconds.
[12:53:17.586] INFO: PixTest:: pg_setup set to default.
[12:53:17.589] INFO: PixTestXray::doPhRun() done
[12:53:17.736] INFO: enter test to run
[12:53:57.909] INFO: test: HighRate no parameter change
[12:53:57.909] INFO: running: highrate
[12:53:57.929] INFO: ----------------------------------------------------------------------
[12:53:57.930] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:53:57.930] INFO: ----------------------------------------------------------------------
[12:53:58.094] INFO: Expecting 768 events.
[12:53:59.227] INFO: 768 events read in total (418ms).
[12:53:59.228] INFO: Test took 1269ms.
[12:54:00.031] INFO: Expecting 41600 events.
[12:54:03.092] INFO: 41600 events read in total (2534ms).
[12:54:03.093] INFO: Test took 3838ms.
[12:54:03.125] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:03.856] INFO: Expecting 41600 events.
[12:54:07.052] INFO: 41600 events read in total (2670ms).
[12:54:07.053] INFO: Test took 3910ms.
[12:54:07.086] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:07.810] INFO: Expecting 41600 events.
[12:54:11.051] INFO: 41600 events read in total (2714ms).
[12:54:11.052] INFO: Test took 3948ms.
[12:54:11.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:11.811] INFO: Expecting 41600 events.
[12:54:15.026] INFO: 41600 events read in total (2688ms).
[12:54:15.027] INFO: Test took 3924ms.
[12:54:15.061] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:15.788] INFO: Expecting 41600 events.
[12:54:19.005] INFO: 41600 events read in total (2690ms).
[12:54:19.006] INFO: Test took 3927ms.
[12:54:19.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:19.762] INFO: Expecting 41600 events.
[12:54:22.906] INFO: 41600 events read in total (2618ms).
[12:54:22.907] INFO: Test took 3851ms.
[12:54:22.940] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:23.662] INFO: Expecting 41600 events.
[12:54:26.905] INFO: 41600 events read in total (2716ms).
[12:54:26.906] INFO: Test took 3949ms.
[12:54:26.940] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:27.665] INFO: Expecting 41600 events.
[12:54:30.910] INFO: 41600 events read in total (2718ms).
[12:54:30.911] INFO: Test took 3954ms.
[12:54:30.944] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:31.669] INFO: Expecting 41600 events.
[12:54:34.762] INFO: 41600 events read in total (2566ms).
[12:54:34.763] INFO: Test took 3800ms.
[12:54:34.797] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:35.521] INFO: Expecting 41600 events.
[12:54:38.772] INFO: 41600 events read in total (2725ms).
[12:54:38.773] INFO: Test took 3959ms.
[12:54:38.806] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:39.532] INFO: Expecting 41600 events.
[12:54:42.729] INFO: 41600 events read in total (2670ms).
[12:54:42.730] INFO: Test took 3907ms.
[12:54:42.763] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:43.485] INFO: Expecting 41600 events.
[12:54:46.725] INFO: 41600 events read in total (2713ms).
[12:54:46.726] INFO: Test took 3944ms.
[12:54:46.760] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:47.484] INFO: Expecting 41600 events.
[12:54:50.724] INFO: 41600 events read in total (2713ms).
[12:54:50.725] INFO: Test took 3946ms.
[12:54:50.759] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:51.485] INFO: Expecting 41600 events.
[12:54:54.683] INFO: 41600 events read in total (2672ms).
[12:54:54.684] INFO: Test took 3906ms.
[12:54:54.717] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:55.442] INFO: Expecting 41600 events.
[12:54:58.665] INFO: 41600 events read in total (2696ms).
[12:54:58.666] INFO: Test took 3932ms.
[12:54:58.700] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:54:59.423] INFO: Expecting 41600 events.
[12:55:02.637] INFO: 41600 events read in total (2687ms).
[12:55:02.638] INFO: Test took 3921ms.
[12:55:02.671] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:03.395] INFO: Expecting 41600 events.
[12:55:06.635] INFO: 41600 events read in total (2713ms).
[12:55:06.636] INFO: Test took 3948ms.
[12:55:06.669] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:07.394] INFO: Expecting 41600 events.
[12:55:10.617] INFO: 41600 events read in total (2696ms).
[12:55:10.618] INFO: Test took 3932ms.
[12:55:10.651] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:11.375] INFO: Expecting 41600 events.
[12:55:14.578] INFO: 41600 events read in total (2676ms).
[12:55:14.579] INFO: Test took 3911ms.
[12:55:14.612] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:15.337] INFO: Expecting 41600 events.
[12:55:18.440] INFO: 41600 events read in total (2576ms).
[12:55:18.441] INFO: Test took 3811ms.
[12:55:18.473] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:18.840] INFO: enter test to run
[12:56:06.204] INFO: test: HighRate no parameter change
[12:56:06.204] INFO: running: highrate
[12:56:06.205] INFO: ----------------------------------------------------------------------
[12:56:06.205] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:56:06.205] INFO: ----------------------------------------------------------------------
[12:56:06.823] INFO: Expecting 208000 events.
[12:56:18.598] INFO: 208000 events read in total (11248ms).
[12:56:18.601] INFO: Test took 12386ms.
[12:56:18.745] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:56:18.997] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 2 0 0 0 1 1 0 0 0
[12:56:18.997] INFO: number of red-efficiency pixels: 88 30 76 129 133 175 144 97 69 116 93 84 110 57 25 19
[12:56:18.997] INFO: number of X-ray hits detected: 68478 42640 69312 102544 111276 120103 112457 80696 73850 87867 91887 76012 79758 47291 19598 21201
[12:56:18.997] 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:18.997] INFO: number of Vcal hits detected: 207911 207970 207923 207867 207864 207818 207852 207805 207931 207883 207907 207866 207839 207943 207975 207981
[12:56:18.997] INFO: Vcal hit fiducial 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
[12:56:18.997] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0 99.9 99.9 100.0 100.0 100.0
[12:56:18.997] INFO: X-ray hit rate [MHz/cm2]: 20.1 12.5 20.3 30.1 32.6 35.2 33.0 23.7 21.6 25.8 26.9 22.3 23.4 13.9 5.7 6.2
[12:56:18.997] INFO: PixTestHighRate::doXPixelAlive() done
[12:56:19.045] INFO: PixTest:: pg_setup set to default.
[12:56:19.060] INFO: enter test to run
[12:56:49.412] INFO: test: HighRate no parameter change
[12:56:49.412] INFO: running: highrate
[12:56:49.413] INFO: ----------------------------------------------------------------------
[12:56:49.413] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:56:49.413] INFO: ----------------------------------------------------------------------
[12:56:50.032] INFO: Expecting 208000 events.
[12:57:03.513] INFO: 208000 events read in total (12954ms).
[12:57:03.518] INFO: Test took 14094ms.
[12:57:03.797] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:04.092] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 2 0 0 0 1 1 0 0 0
[12:57:04.092] INFO: number of red-efficiency pixels: 279 96 217 356 427 604 556 301 185 328 280 222 247 143 50 46
[12:57:04.092] INFO: number of X-ray hits detected: 140990 87982 141149 209612 228619 246774 231797 165435 151794 180687 189208 156497 162326 98096 40552 43727
[12:57:04.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
[12:57:04.092] INFO: number of Vcal hits detected: 207691 207901 207766 207622 207534 207326 207384 207574 207808 207652 207705 207718 207697 207855 207947 207953
[12:57:04.092] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.7 99.7 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[12:57:04.092] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.7 99.7 99.8 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[12:57:04.092] INFO: X-ray hit rate [MHz/cm2]: 41.3 25.8 41.4 61.4 67.0 72.3 67.9 48.5 44.5 53.0 55.5 45.9 47.6 28.8 11.9 12.8
[12:57:04.092] INFO: PixTestHighRate::doXPixelAlive() done
[12:57:04.140] INFO: PixTest:: pg_setup set to default.
[12:57:04.157] INFO: enter test to run
[12:57:30.468] INFO: test: HighRate no parameter change
[12:57:30.468] INFO: running: highrate
[12:57:30.469] INFO: ----------------------------------------------------------------------
[12:57:30.469] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:57:30.469] INFO: ----------------------------------------------------------------------
[12:57:31.086] INFO: Expecting 208000 events.
[12:57:46.302] INFO: 208000 events read in total (14689ms).
[12:57:46.310] INFO: Test took 15831ms.
[12:57:46.735] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:47.076] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 2 0 0 0 1 1 0 0 0
[12:57:47.076] INFO: number of red-efficiency pixels: 539 201 456 848 972 1340 1258 696 411 762 641 452 593 370 77 80
[12:57:47.076] INFO: number of X-ray hits detected: 214055 131862 212633 314888 343554 370986 346578 250266 230028 272064 285374 236256 245234 147875 61033 66113
[12:57:47.076] 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:47.076] INFO: number of Vcal hits detected: 207326 207770 207447 206975 206806 206194 206319 207024 207559 207125 207258 207451 207295 207578 207922 207918
[12:57:47.076] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.8 99.6 99.5 99.2 99.3 99.6 99.8 99.6 99.7 99.8 99.7 99.8 100.0 100.0
[12:57:47.076] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.7 99.5 99.4 99.1 99.2 99.5 99.8 99.6 99.6 99.7 99.7 99.8 100.0 100.0
[12:57:47.076] INFO: X-ray hit rate [MHz/cm2]: 62.7 38.6 62.3 92.3 100.7 108.7 101.6 73.4 67.4 79.7 83.6 69.2 71.9 43.3 17.9 19.4
[12:57:47.076] INFO: PixTestHighRate::doXPixelAlive() done
[12:57:47.122] INFO: PixTest:: pg_setup set to default.
[12:57:47.140] INFO: enter test to run
[12:57:56.652] INFO: test: exit no parameter change
[12:57:57.054] QUIET: Connection to board 33 closed.
[12:57:57.075] INFO: pXar: this is the end, my friend