[11:03:16.612] INFO: *** Welcome to pxar ***
[11:03:16.612] INFO: *** Today: 2016/04/15
[11:03:16.632] INFO: *** Version: v1.9.0-796-gef167-dirty
[11:03:16.632] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//dacParameters35_C15.dat
[11:03:16.674] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:03:16.674] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//defaultMaskFile.dat
[11:03:16.674] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C15.dat
[11:03:16.799] INFO: clk: 4
[11:03:16.799] INFO: ctr: 4
[11:03:16.799] INFO: sda: 19
[11:03:16.799] INFO: tin: 9
[11:03:16.799] INFO: level: 15
[11:03:16.799] INFO: triggerdelay: 0
[11:03:16.799] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[11:03:16.799] INFO: Log level: INFO
[11:03:16.816] QUIET: Connection to board DTB_WREKRL opened.
[11:03:16.820] 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:
------------------------------------------------------
[11:03:16.823] INFO: RPC call hashes of host and DTB match: 398089610
[11:03:18.355] INFO: DUT info:
[11:03:18.355] INFO: The DUT currently contains the following objects:
[11:03:18.355] INFO: 2 TBM Cores tbm08c (2 ON)
[11:03:18.355] INFO: TBM Core alpha (0): 7 registers set
[11:03:18.355] INFO: TBM Core beta (1): 7 registers set
[11:03:18.355] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:03:18.355] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.355] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.355] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.355] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.355] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.355] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.356] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.356] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.356] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.356] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.356] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.356] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.356] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.356] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.356] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.356] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:18.759] INFO: enter 'restricted' command line mode
[11:03:18.759] INFO: enter test to run
[11:03:32.403] INFO: test: PixelAlive no parameter change
[11:03:32.403] INFO: running: pixelalive
[11:03:32.412] INFO: ----------------------------------------------------------------------
[11:03:32.412] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:03:32.412] INFO: ----------------------------------------------------------------------
[11:03:32.734] INFO: Expecting 41600 events.
[11:03:37.072] INFO: 41600 events read in total (3620ms).
[11:03:37.236] INFO: Test took 4822ms.
[11:03:37.246] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:03:37.506] INFO: PixTestAlive::aliveTest() done
[11:03:37.506] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:03:37.539] INFO: enter test to run
[11:04:38.394] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[11:04:38.394] INFO: running: highrate
[11:04:38.394] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[11:04:38.625] INFO: ----------------------------------------------------------------------
[11:04:38.625] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[11:04:38.625] INFO: ----------------------------------------------------------------------
[11:04:38.625] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[11:04:38.625] INFO: edge/corner pixel THR is adjusted
[11:04:38.625] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:04:39.584] INFO: Collecting data for 5 seconds...
[11:04:44.601] INFO: Done with hot pixel readout
[11:04:56.968] INFO: PixTest:: pg_setup set to default.
[11:04:56.996] INFO: 74 hot pixels found in step 0
[11:04:57.992] INFO: Collecting data for 5 seconds...
[11:05:03.009] INFO: Done with hot pixel readout
[11:05:15.176] INFO: PixTest:: pg_setup set to default.
[11:05:15.177] INFO: 76 hot pixels found in step 1
[11:05:16.174] INFO: Collecting data for 5 seconds...
[11:05:21.182] INFO: Done with hot pixel readout
[11:05:31.064] INFO: PixTest:: pg_setup set to default.
[11:05:31.064] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:05:31.065] INFO: 60 hot pixels found in step 2
[11:05:32.061] INFO: Collecting data for 5 seconds...
[11:05:37.076] INFO: Done with hot pixel readout
[11:05:49.168] INFO: PixTest:: pg_setup set to default.
[11:05:49.168] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:05:49.168] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:05:49.169] INFO: 54 hot pixels found in step 3
[11:05:50.166] INFO: Collecting data for 5 seconds...
[11:05:55.183] INFO: Done with hot pixel readout
[11:06:07.361] INFO: PixTest:: pg_setup set to default.
[11:06:07.361] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:06:07.361] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:06:07.362] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:06:07.362] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:06:07.362] INFO: 62 hot pixels found in step 4
[11:06:08.361] INFO: Collecting data for 5 seconds...
[11:06:13.378] INFO: Done with hot pixel readout
[11:06:25.639] INFO: PixTest:: pg_setup set to default.
[11:06:25.639] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:06:25.639] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:06:25.639] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:06:25.640] INFO: 47 hot pixels found in step 5
[11:06:26.637] INFO: Collecting data for 5 seconds...
[11:06:31.655] INFO: Done with hot pixel readout
[11:06:43.949] INFO: PixTest:: pg_setup set to default.
[11:06:43.949] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:06:43.949] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:06:43.949] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:06:43.949] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:06:43.949] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:06:43.949] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:06:43.949] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:06:43.950] INFO: 60 hot pixels found in step 6
[11:06:44.947] INFO: Collecting data for 5 seconds...
[11:06:49.964] INFO: Done with hot pixel readout
[11:07:01.998] INFO: PixTest:: pg_setup set to default.
[11:07:01.998] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:01.998] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:01.998] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:01.998] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:01.998] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:01.998] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:01.998] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:01.998] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:01.998] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:01.998] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:01.999] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:01.999] INFO: 53 hot pixels found in step 7
[11:07:02.995] INFO: Collecting data for 5 seconds...
[11:07:08.013] INFO: Done with hot pixel readout
[11:07:20.335] INFO: PixTest:: pg_setup set to default.
[11:07:20.335] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:20.335] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:20.336] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:20.336] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:20.336] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:20.336] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:20.336] INFO: 42 hot pixels found in step 8
[11:07:21.334] INFO: Collecting data for 5 seconds...
[11:07:26.350] INFO: Done with hot pixel readout
[11:07:38.712] INFO: PixTest:: pg_setup set to default.
[11:07:38.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:38.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:38.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:38.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:38.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:38.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:38.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:38.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:38.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:38.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:38.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:38.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:38.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:38.712] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:38.713] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:38.713] INFO: 47 hot pixels found in step 9
[11:07:39.710] INFO: Collecting data for 5 seconds...
[11:07:44.726] INFO: Done with hot pixel readout
[11:07:57.109] INFO: PixTest:: pg_setup set to default.
[11:07:57.109] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:57.109] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:57.109] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:57.109] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:57.109] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:57.109] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:57.109] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:57.109] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:57.109] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:57.109] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:57.109] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:57.109] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:57.109] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:57.109] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:07:57.110] INFO: 44 hot pixels found in step 10
[11:07:58.106] INFO: Collecting data for 5 seconds...
[11:08:03.122] INFO: Done with hot pixel readout
[11:08:15.457] INFO: PixTest:: pg_setup set to default.
[11:08:15.457] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:15.457] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:15.458] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:15.458] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:15.458] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:15.458] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:15.458] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:15.458] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:15.458] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:15.458] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:15.458] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:15.458] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:15.458] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:15.458] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:15.459] INFO: 54 hot pixels found in step 11
[11:08:16.455] INFO: Collecting data for 5 seconds...
[11:08:21.472] INFO: Done with hot pixel readout
[11:08:33.794] INFO: PixTest:: pg_setup set to default.
[11:08:33.794] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:33.794] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:33.794] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:33.794] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:33.794] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:33.794] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:33.794] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:33.794] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:33.794] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:33.794] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:33.794] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:33.795] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:33.795] INFO: 39 hot pixels found in step 12
[11:08:34.792] INFO: Collecting data for 5 seconds...
[11:08:39.809] INFO: Done with hot pixel readout
[11:08:52.178] INFO: PixTest:: pg_setup set to default.
[11:08:52.178] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:52.178] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:52.178] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:52.178] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:52.178] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:52.178] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:52.178] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:52.178] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:52.178] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:52.178] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:52.178] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:08:52.179] INFO: 41 hot pixels found in step 13
[11:08:53.175] INFO: Collecting data for 5 seconds...
[11:08:58.192] INFO: Done with hot pixel readout
[11:09:10.540] INFO: PixTest:: pg_setup set to default.
[11:09:10.540] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:09:10.540] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:09:10.540] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:09:10.540] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:09:10.540] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:09:10.540] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:09:10.540] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:09:10.540] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:09:10.540] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:09:10.540] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:09:10.540] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:09:10.540] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:09:10.540] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:09:10.540] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[11:09:10.541] INFO: 38 hot pixels found in step 14
[11:09:10.581] INFO: 38 hot pixels could not be trimmed and have been masked.
[11:09:10.585] INFO: PixTest::trimHotPixels() done
[11:09:10.585] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C0.dat
[11:09:10.590] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C1.dat
[11:09:10.597] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C2.dat
[11:09:10.603] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C3.dat
[11:09:10.608] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C4.dat
[11:09:10.613] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C5.dat
[11:09:10.618] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C6.dat
[11:09:10.624] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C7.dat
[11:09:10.629] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C8.dat
[11:09:10.634] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C9.dat
[11:09:10.640] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C10.dat
[11:09:10.645] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C11.dat
[11:09:10.650] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C12.dat
[11:09:10.655] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C13.dat
[11:09:10.661] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C14.dat
[11:09:10.666] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C15.dat
[11:09:10.671] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//defaultMaskFile.dat
[11:09:10.681] INFO: enter test to run
[11:09:42.616] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[11:09:42.616] INFO: running: highrate
[11:09:42.621] INFO: ----------------------------------------------------------------------
[11:09:42.621] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:09:42.621] INFO: ----------------------------------------------------------------------
[11:09:42.621] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:09:42.621] INFO: edge/corner pixel THR is adjusted
[11:09:42.621] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:09:43.579] INFO: Collecting data for 1 seconds...
[11:09:44.582] INFO: Done with hot pixel readout
[11:09:48.944] INFO: PixTest:: pg_setup set to default.
[11:09:48.945] INFO: 0 hot pixels found in step 0
[11:09:48.950] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:09:49.032] INFO: PixTest::trimHotPixels() done
[11:09:49.032] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C0.dat
[11:09:49.040] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C1.dat
[11:09:49.045] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C2.dat
[11:09:49.051] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C3.dat
[11:09:49.056] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C4.dat
[11:09:49.061] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C5.dat
[11:09:49.066] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C6.dat
[11:09:49.072] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C7.dat
[11:09:49.077] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C8.dat
[11:09:49.082] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C9.dat
[11:09:49.087] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C10.dat
[11:09:49.092] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C11.dat
[11:09:49.098] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C12.dat
[11:09:49.103] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C13.dat
[11:09:49.108] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C14.dat
[11:09:49.113] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//trimParameters35_C15.dat
[11:09:49.119] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-4-02_FPIXTest-17C-FNAL-160408-1158_2016-04-08_11h58m_1460134718/000_FPIXTest_p17//defaultMaskFile.dat
[11:09:49.128] INFO: enter test to run
[11:11:03.983] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:11:03.983] INFO: running: xray
[11:11:03.985] INFO: ----------------------------------------------------------------------
[11:11:03.985] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:11:03.985] INFO: ----------------------------------------------------------------------
[11:11:04.948] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:11:15.800] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[11:11:45.435] INFO: Resuming triggers.
[11:11:56.287] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[11:12:25.797] INFO: Resuming triggers.
[11:12:36.653] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[11:13:06.333] INFO: Resuming triggers.
[11:13:17.188] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[11:13:46.809] INFO: Resuming triggers.
[11:13:57.665] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[11:14:27.237] INFO: Resuming triggers.
[11:14:38.094] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[11:15:07.733] INFO: Resuming triggers.
[11:15:18.589] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[11:15:43.189] INFO: Resuming triggers.
[11:15:54.045] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[11:16:23.575] INFO: Resuming triggers.
[11:16:34.430] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[11:17:04.078] INFO: Resuming triggers.
[11:17:06.719] INFO: data taking finished, elapsed time: 100 seconds.
[11:17:14.228] INFO: PixTest:: pg_setup set to default.
[11:17:14.231] INFO: PixTestXray::doPhRun() done
[11:17:14.369] INFO: enter test to run
[11:18:13.300] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:18:13.300] INFO: running: xray
[11:18:13.301] INFO: ----------------------------------------------------------------------
[11:18:13.301] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:18:13.301] INFO: ----------------------------------------------------------------------
[11:18:14.265] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:18:20.227] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[11:18:50.292] INFO: Resuming triggers.
[11:18:56.253] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:19:26.289] INFO: Resuming triggers.
[11:19:32.257] INFO: run duration 17 seconds, buffer almost full (81%), pausing triggers.
[11:20:02.325] INFO: Resuming triggers.
[11:20:08.290] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:20:38.413] INFO: Resuming triggers.
[11:20:44.375] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[11:21:14.516] INFO: Resuming triggers.
[11:21:20.481] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[11:21:50.665] INFO: Resuming triggers.
[11:21:56.630] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[11:22:26.292] INFO: Resuming triggers.
[11:22:32.255] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[11:23:02.207] INFO: Resuming triggers.
[11:23:08.175] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[11:23:37.974] INFO: Resuming triggers.
[11:23:43.936] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[11:24:14.156] INFO: Resuming triggers.
[11:24:20.122] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[11:24:49.374] INFO: Resuming triggers.
[11:24:55.341] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[11:25:25.433] INFO: Resuming triggers.
[11:25:31.399] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[11:25:56.829] INFO: Resuming triggers.
[11:26:02.793] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[11:26:33.250] INFO: Resuming triggers.
[11:26:39.215] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[11:27:09.393] INFO: Resuming triggers.
[11:27:15.356] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[11:27:44.935] INFO: Resuming triggers.
[11:27:49.841] INFO: data taking finished, elapsed time: 100 seconds.
[11:28:14.788] INFO: PixTest:: pg_setup set to default.
[11:28:14.791] INFO: PixTestXray::doPhRun() done
[11:28:14.940] INFO: enter test to run
[11:29:17.431] INFO: test: HighRate no parameter change
[11:29:17.431] INFO: running: highrate
[11:29:17.432] INFO: ----------------------------------------------------------------------
[11:29:17.432] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:29:17.432] INFO: ----------------------------------------------------------------------
[11:29:17.574] INFO: Expecting 768 events.
[11:29:18.707] INFO: 768 events read in total (418ms).
[11:29:18.708] INFO: Test took 1268ms.
[11:29:19.511] INFO: Expecting 41600 events.
[11:29:22.652] INFO: 41600 events read in total (2614ms).
[11:29:22.653] INFO: Test took 3938ms.
[11:29:22.692] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:23.411] INFO: Expecting 41600 events.
[11:29:26.648] INFO: 41600 events read in total (2710ms).
[11:29:26.649] INFO: Test took 3937ms.
[11:29:26.689] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:27.404] INFO: Expecting 41600 events.
[11:29:30.703] INFO: 41600 events read in total (2772ms).
[11:29:30.704] INFO: Test took 3995ms.
[11:29:30.743] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:31.454] INFO: Expecting 41600 events.
[11:29:34.771] INFO: 41600 events read in total (2790ms).
[11:29:34.772] INFO: Test took 4008ms.
[11:29:34.812] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:35.521] INFO: Expecting 41600 events.
[11:29:38.816] INFO: 41600 events read in total (2768ms).
[11:29:38.817] INFO: Test took 3986ms.
[11:29:38.857] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:39.570] INFO: Expecting 41600 events.
[11:29:42.883] INFO: 41600 events read in total (2786ms).
[11:29:42.884] INFO: Test took 4006ms.
[11:29:42.924] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:43.632] INFO: Expecting 41600 events.
[11:29:46.928] INFO: 41600 events read in total (2769ms).
[11:29:46.929] INFO: Test took 3984ms.
[11:29:46.970] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:47.679] INFO: Expecting 41600 events.
[11:29:50.974] INFO: 41600 events read in total (2769ms).
[11:29:50.975] INFO: Test took 3984ms.
[11:29:51.016] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:51.729] INFO: Expecting 41600 events.
[11:29:55.033] INFO: 41600 events read in total (2777ms).
[11:29:55.034] INFO: Test took 3997ms.
[11:29:55.076] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:55.789] INFO: Expecting 41600 events.
[11:29:59.089] INFO: 41600 events read in total (2774ms).
[11:29:59.090] INFO: Test took 3993ms.
[11:29:59.130] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:59.844] INFO: Expecting 41600 events.
[11:30:03.144] INFO: 41600 events read in total (2773ms).
[11:30:03.145] INFO: Test took 3995ms.
[11:30:03.185] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:03.900] INFO: Expecting 41600 events.
[11:30:07.209] INFO: 41600 events read in total (2783ms).
[11:30:07.210] INFO: Test took 4005ms.
[11:30:07.250] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:07.961] INFO: Expecting 41600 events.
[11:30:11.265] INFO: 41600 events read in total (2777ms).
[11:30:11.266] INFO: Test took 3996ms.
[11:30:11.307] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:12.019] INFO: Expecting 41600 events.
[11:30:15.322] INFO: 41600 events read in total (2776ms).
[11:30:15.324] INFO: Test took 3998ms.
[11:30:15.364] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:16.078] INFO: Expecting 41600 events.
[11:30:19.380] INFO: 41600 events read in total (2775ms).
[11:30:19.381] INFO: Test took 3996ms.
[11:30:19.422] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:20.134] INFO: Expecting 41600 events.
[11:30:23.446] INFO: 41600 events read in total (2785ms).
[11:30:23.447] INFO: Test took 4005ms.
[11:30:23.487] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:24.200] INFO: Expecting 41600 events.
[11:30:27.505] INFO: 41600 events read in total (2778ms).
[11:30:27.506] INFO: Test took 3999ms.
[11:30:27.547] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:28.260] INFO: Expecting 41600 events.
[11:30:31.548] INFO: 41600 events read in total (2761ms).
[11:30:31.549] INFO: Test took 3981ms.
[11:30:31.589] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:32.304] INFO: Expecting 41600 events.
[11:30:35.564] INFO: 41600 events read in total (2733ms).
[11:30:35.565] INFO: Test took 3957ms.
[11:30:35.604] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:36.318] INFO: Expecting 41600 events.
[11:30:39.459] INFO: 41600 events read in total (2615ms).
[11:30:39.460] INFO: Test took 3835ms.
[11:30:39.499] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:39.852] INFO: enter test to run
[11:31:03.599] INFO: test: HighRate no parameter change
[11:31:03.599] INFO: running: highrate
[11:31:03.599] INFO: ----------------------------------------------------------------------
[11:31:03.599] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:31:03.599] INFO: ----------------------------------------------------------------------
[11:31:04.212] INFO: Expecting 208000 events.
[11:31:16.443] INFO: 208000 events read in total (11704ms).
[11:31:16.447] INFO: Test took 12839ms.
[11:31:16.623] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:16.884] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:31:16.884] INFO: number of red-efficiency pixels: 113 73 142 207 174 169 157 134 121 136 167 98 154 85 27 31
[11:31:16.884] INFO: number of X-ray hits detected: 85745 53803 87048 139858 140063 141456 143483 102542 94296 116731 114788 101254 103944 62153 28098 29910
[11:31:16.884] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:31:16.884] INFO: number of Vcal hits detected: 207886 207927 207856 207789 207807 207826 207838 207860 207874 207860 207831 207901 207843 207914 207973 207969
[11:31:16.885] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[11:31:16.885] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[11:31:16.885] INFO: X-ray hit rate [MHz/cm2]: 25.1 15.8 25.5 41.0 41.1 41.5 42.1 30.1 27.6 34.2 33.6 29.7 30.5 18.2 8.2 8.8
[11:31:16.885] INFO: PixTestHighRate::doXPixelAlive() done
[11:31:16.931] INFO: PixTest:: pg_setup set to default.
[11:31:16.943] INFO: enter test to run
[11:32:05.918] INFO: test: HighRate no parameter change
[11:32:05.918] INFO: running: highrate
[11:32:05.919] INFO: ----------------------------------------------------------------------
[11:32:05.919] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:32:05.919] INFO: ----------------------------------------------------------------------
[11:32:06.536] INFO: Expecting 208000 events.
[11:32:20.860] INFO: 208000 events read in total (13797ms).
[11:32:20.866] INFO: Test took 14938ms.
[11:32:21.210] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:32:21.529] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:32:21.529] INFO: number of red-efficiency pixels: 284 134 517 799 561 586 614 399 341 412 426 346 443 271 56 76
[11:32:21.529] INFO: number of X-ray hits detected: 170254 106953 174102 278713 278119 280641 285296 204779 190156 232328 228876 201417 207083 123271 56216 59712
[11:32:21.529] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:32:21.529] INFO: number of Vcal hits detected: 207694 207861 207398 207092 207340 207341 207323 207552 207639 207565 207534 207626 207519 207705 207942 207921
[11:32:21.529] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.7 99.6 99.7 99.7 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[11:32:21.529] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.6 99.7 99.7 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[11:32:21.529] INFO: X-ray hit rate [MHz/cm2]: 49.9 31.3 51.0 81.7 81.5 82.3 83.6 60.0 55.7 68.1 67.1 59.0 60.7 36.1 16.5 17.5
[11:32:21.529] INFO: PixTestHighRate::doXPixelAlive() done
[11:32:21.581] INFO: PixTest:: pg_setup set to default.
[11:32:21.597] INFO: enter test to run
[11:32:59.566] INFO: test: HighRate no parameter change
[11:32:59.566] INFO: running: highrate
[11:32:59.567] INFO: ----------------------------------------------------------------------
[11:32:59.567] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:32:59.567] INFO: ----------------------------------------------------------------------
[11:33:00.180] INFO: Expecting 208000 events.
[11:33:16.722] INFO: 208000 events read in total (16016ms).
[11:33:16.730] INFO: Test took 17154ms.
[11:33:17.243] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:33:17.616] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:33:17.616] INFO: number of red-efficiency pixels: 594 312 1013 1786 1376 1358 1306 873 782 857 933 711 994 579 112 112
[11:33:17.616] INFO: number of X-ray hits detected: 254252 160882 259546 415061 413345 418172 424102 304404 282349 346420 342612 300188 310139 184285 83795 89762
[11:33:17.616] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:33:17.616] INFO: number of Vcal hits detected: 207256 207649 206345 205340 206011 206151 206273 206806 207081 206976 206898 207174 206812 207297 207879 207884
[11:33:17.616] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.3 98.9 99.1 99.2 99.2 99.5 99.6 99.6 99.5 99.6 99.5 99.7 99.9 99.9
[11:33:17.616] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.2 98.7 99.0 99.1 99.2 99.4 99.6 99.5 99.5 99.6 99.4 99.7 99.9 99.9
[11:33:17.616] INFO: X-ray hit rate [MHz/cm2]: 74.5 47.2 76.1 121.7 121.2 122.6 124.3 89.2 82.8 101.5 100.4 88.0 90.9 54.0 24.6 26.3
[11:33:17.616] INFO: PixTestHighRate::doXPixelAlive() done
[11:33:17.674] INFO: PixTest:: pg_setup set to default.
[11:33:17.688] INFO: enter test to run
[11:33:41.693] INFO: test: exit no parameter change
[11:33:42.018] QUIET: Connection to board 33 closed.
[11:33:42.020] INFO: pXar: this is the end, my friend