[13:48:17.209] INFO: *** Welcome to pxar ***
[13:48:17.210] INFO: *** Today: 2016/05/30
[13:48:17.225] INFO: *** Version: v1.9.0-796-gef167-dirty
[13:48:17.225] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//dacParameters35_C15.dat
[13:48:17.249] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:48:17.249] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//defaultMaskFile.dat
[13:48:17.255] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C15.dat
[13:48:17.369] INFO: clk: 4
[13:48:17.369] INFO: ctr: 4
[13:48:17.369] INFO: sda: 19
[13:48:17.369] INFO: tin: 9
[13:48:17.369] INFO: level: 15
[13:48:17.369] INFO: triggerdelay: 0
[13:48:17.369] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[13:48:17.369] INFO: Log level: INFO
[13:48:17.388] QUIET: Connection to board DTB_WREKRL opened.
[13:48:17.391] 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:
------------------------------------------------------
[13:48:17.394] INFO: RPC call hashes of host and DTB match: 398089610
[13:48:18.924] INFO: DUT info:
[13:48:18.924] INFO: The DUT currently contains the following objects:
[13:48:18.924] INFO: 2 TBM Cores tbm08c (2 ON)
[13:48:18.924] INFO: TBM Core alpha (0): 7 registers set
[13:48:18.924] INFO: TBM Core beta (1): 7 registers set
[13:48:18.924] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:48:18.924] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:18.924] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:18.924] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:18.924] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:18.924] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:18.924] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:18.924] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:18.924] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:18.924] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:18.924] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:18.925] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:18.925] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:18.925] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:18.925] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:18.925] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:18.925] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:19.328] INFO: enter 'restricted' command line mode
[13:48:19.328] INFO: enter test to run
[13:48:21.949] INFO: test: PixelAlive no parameter change
[13:48:21.949] INFO: running: pixelalive
[13:48:21.958] INFO: ----------------------------------------------------------------------
[13:48:21.958] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:48:21.958] INFO: ----------------------------------------------------------------------
[13:48:22.275] INFO: Expecting 41600 events.
[13:48:26.590] INFO: 41600 events read in total (3597ms).
[13:48:26.758] INFO: Test took 4798ms.
[13:48:26.773] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:27.041] INFO: PixTestAlive::aliveTest() done
[13:48:27.041] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:48:27.077] INFO: enter test to run
[13:48:41.164] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:48:41.164] INFO: running: highrate
[13:48:41.165] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:48:41.392] INFO: ----------------------------------------------------------------------
[13:48:41.392] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:48:41.392] INFO: ----------------------------------------------------------------------
[13:48:41.392] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:48:41.392] INFO: edge/corner pixel THR is adjusted
[13:48:41.392] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:48:42.351] INFO: Collecting data for 5 seconds...
[13:48:47.367] INFO: Done with hot pixel readout
[13:48:59.233] INFO: PixTest:: pg_setup set to default.
[13:48:59.234] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:48:59.234] INFO: 18 hot pixels found in step 0
[13:49:00.229] INFO: Collecting data for 5 seconds...
[13:49:05.246] INFO: Done with hot pixel readout
[13:49:17.083] INFO: PixTest:: pg_setup set to default.
[13:49:17.084] INFO: 18 hot pixels found in step 1
[13:49:18.076] INFO: Collecting data for 5 seconds...
[13:49:23.092] INFO: Done with hot pixel readout
[13:49:34.926] INFO: PixTest:: pg_setup set to default.
[13:49:34.927] INFO: 20 hot pixels found in step 2
[13:49:35.920] INFO: Collecting data for 5 seconds...
[13:49:40.936] INFO: Done with hot pixel readout
[13:49:52.775] INFO: PixTest:: pg_setup set to default.
[13:49:52.776] INFO: 17 hot pixels found in step 3
[13:49:53.769] INFO: Collecting data for 5 seconds...
[13:49:58.786] INFO: Done with hot pixel readout
[13:50:10.750] INFO: PixTest:: pg_setup set to default.
[13:50:10.750] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:50:10.751] INFO: 15 hot pixels found in step 4
[13:50:11.742] INFO: Collecting data for 5 seconds...
[13:50:16.759] INFO: Done with hot pixel readout
[13:50:28.630] INFO: PixTest:: pg_setup set to default.
[13:50:28.630] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:50:28.630] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:50:28.630] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:50:28.631] INFO: 19 hot pixels found in step 5
[13:50:29.623] INFO: Collecting data for 5 seconds...
[13:50:34.640] INFO: Done with hot pixel readout
[13:50:46.383] INFO: PixTest:: pg_setup set to default.
[13:50:46.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:50:46.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:50:46.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:50:46.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:50:46.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:50:46.384] INFO: 18 hot pixels found in step 6
[13:50:47.376] INFO: Collecting data for 5 seconds...
[13:50:52.392] INFO: Done with hot pixel readout
[13:51:04.212] INFO: PixTest:: pg_setup set to default.
[13:51:04.212] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:04.212] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:04.212] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:04.212] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:04.212] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:04.212] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:04.213] INFO: 15 hot pixels found in step 7
[13:51:05.205] INFO: Collecting data for 5 seconds...
[13:51:10.222] INFO: Done with hot pixel readout
[13:51:22.015] INFO: PixTest:: pg_setup set to default.
[13:51:22.015] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:22.015] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:22.015] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:22.015] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:22.015] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:22.015] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:22.016] INFO: 15 hot pixels found in step 8
[13:51:23.009] INFO: Collecting data for 5 seconds...
[13:51:28.025] INFO: Done with hot pixel readout
[13:51:39.956] INFO: PixTest:: pg_setup set to default.
[13:51:39.956] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:39.956] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:39.956] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:39.956] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:39.956] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:39.956] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:39.956] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:39.957] INFO: 14 hot pixels found in step 9
[13:51:40.949] INFO: Collecting data for 5 seconds...
[13:51:45.965] INFO: Done with hot pixel readout
[13:51:57.839] INFO: PixTest:: pg_setup set to default.
[13:51:57.839] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:57.839] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:57.839] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:57.839] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:57.839] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:57.839] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:57.839] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:51:57.840] INFO: 10 hot pixels found in step 10
[13:51:58.833] INFO: Collecting data for 5 seconds...
[13:52:03.850] INFO: Done with hot pixel readout
[13:52:15.728] INFO: PixTest:: pg_setup set to default.
[13:52:15.728] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:15.728] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:15.728] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:15.728] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:15.728] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:15.728] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:15.728] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:15.729] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:15.729] INFO: 13 hot pixels found in step 11
[13:52:16.721] INFO: Collecting data for 5 seconds...
[13:52:21.737] INFO: Done with hot pixel readout
[13:52:33.572] INFO: PixTest:: pg_setup set to default.
[13:52:33.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:33.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:33.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:33.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:33.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:33.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:33.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:33.573] INFO: 10 hot pixels found in step 12
[13:52:34.566] INFO: Collecting data for 5 seconds...
[13:52:39.582] INFO: Done with hot pixel readout
[13:52:51.465] INFO: PixTest:: pg_setup set to default.
[13:52:51.465] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:51.465] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:51.465] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:51.465] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:51.465] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:51.465] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:52:51.466] INFO: 10 hot pixels found in step 13
[13:52:52.458] INFO: Collecting data for 5 seconds...
[13:52:57.473] INFO: Done with hot pixel readout
[13:53:09.344] INFO: PixTest:: pg_setup set to default.
[13:53:09.344] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:53:09.344] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:53:09.344] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:53:09.344] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:53:09.345] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:53:09.345] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:53:09.345] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:53:09.345] INFO: 11 hot pixels found in step 14
[13:53:09.381] INFO: 11 hot pixels could not be trimmed and have been masked.
[13:53:09.385] INFO: PixTest::trimHotPixels() done
[13:53:09.385] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C0.dat
[13:53:09.395] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C1.dat
[13:53:09.415] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C2.dat
[13:53:09.427] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C3.dat
[13:53:09.434] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C4.dat
[13:53:09.440] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C5.dat
[13:53:09.445] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C6.dat
[13:53:09.450] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C7.dat
[13:53:09.455] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C8.dat
[13:53:09.461] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C9.dat
[13:53:09.467] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C10.dat
[13:53:09.472] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C11.dat
[13:53:09.477] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C12.dat
[13:53:09.483] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C13.dat
[13:53:09.488] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C14.dat
[13:53:09.493] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C15.dat
[13:53:09.499] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//defaultMaskFile.dat
[13:53:09.509] INFO: enter test to run
[13:54:14.346] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:54:14.346] INFO: running: highrate
[13:54:14.350] INFO: ----------------------------------------------------------------------
[13:54:14.350] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:54:14.350] INFO: ----------------------------------------------------------------------
[13:54:14.350] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:54:14.350] INFO: edge/corner pixel THR is adjusted
[13:54:14.350] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:54:15.308] INFO: Collecting data for 1 seconds...
[13:54:16.312] INFO: Done with hot pixel readout
[13:54:20.360] INFO: PixTest:: pg_setup set to default.
[13:54:20.361] INFO: 0 hot pixels found in step 0
[13:54:20.366] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:54:20.454] INFO: PixTest::trimHotPixels() done
[13:54:20.454] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C0.dat
[13:54:20.463] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C1.dat
[13:54:20.472] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C2.dat
[13:54:20.477] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C3.dat
[13:54:20.482] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C4.dat
[13:54:20.487] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C5.dat
[13:54:20.492] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C6.dat
[13:54:20.497] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C7.dat
[13:54:20.502] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C8.dat
[13:54:20.508] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C9.dat
[13:54:20.513] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C10.dat
[13:54:20.518] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C11.dat
[13:54:20.523] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C12.dat
[13:54:20.528] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C13.dat
[13:54:20.533] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C14.dat
[13:54:20.538] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//trimParameters35_C15.dat
[13:54:20.574] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-3-11_FPIXTest-17C-FNAL-160526-1044_2016-05-26_10h44m_1464277493/000_FPIXTest_p17//defaultMaskFile.dat
[13:54:20.590] INFO: enter test to run
[13:54:37.050] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:54:37.050] INFO: running: xray
[13:54:37.051] INFO: ----------------------------------------------------------------------
[13:54:37.051] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:54:37.051] INFO: ----------------------------------------------------------------------
[13:54:38.014] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:54:49.389] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:55:18.622] INFO: Resuming triggers.
[13:55:29.000] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[13:55:59.370] INFO: Resuming triggers.
[13:56:10.750] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[13:56:40.013] INFO: Resuming triggers.
[13:56:51.392] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[13:57:20.631] INFO: Resuming triggers.
[13:57:32.010] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[13:58:01.269] INFO: Resuming triggers.
[13:58:12.646] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[13:58:41.926] INFO: Resuming triggers.
[13:58:53.308] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[13:59:22.584] INFO: Resuming triggers.
[13:59:33.962] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[14:00:03.200] INFO: Resuming triggers.
[14:00:12.489] INFO: data taking finished, elapsed time: 100 seconds.
[14:00:36.500] INFO: PixTest:: pg_setup set to default.
[14:00:36.504] INFO: PixTestXray::doPhRun() done
[14:00:36.675] INFO: enter test to run
[14:13:05.497] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:13:05.497] INFO: running: xray
[14:13:05.499] INFO: ----------------------------------------------------------------------
[14:13:05.499] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:13:05.499] INFO: ----------------------------------------------------------------------
[14:13:06.473] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:13:13.060] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:13:43.070] INFO: Resuming triggers.
[14:13:49.659] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[14:14:19.644] INFO: Resuming triggers.
[14:14:26.237] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[14:14:56.250] INFO: Resuming triggers.
[14:15:02.842] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[14:15:32.875] INFO: Resuming triggers.
[14:15:39.464] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[14:16:09.492] INFO: Resuming triggers.
[14:16:16.086] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[14:16:46.103] INFO: Resuming triggers.
[14:16:52.696] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[14:17:22.643] INFO: Resuming triggers.
[14:17:29.235] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[14:17:59.249] INFO: Resuming triggers.
[14:18:05.838] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[14:18:35.945] INFO: Resuming triggers.
[14:18:42.538] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[14:19:12.592] INFO: Resuming triggers.
[14:19:19.188] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[14:19:49.176] INFO: Resuming triggers.
[14:19:55.772] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[14:20:25.899] INFO: Resuming triggers.
[14:20:32.493] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[14:21:02.580] INFO: Resuming triggers.
[14:21:09.171] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[14:21:39.230] INFO: Resuming triggers.
[14:21:45.820] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[14:22:15.795] INFO: Resuming triggers.
[14:22:17.232] INFO: data taking finished, elapsed time: 100 seconds.
[14:22:24.113] INFO: PixTest:: pg_setup set to default.
[14:22:24.116] INFO: PixTestXray::doPhRun() done
[14:22:24.266] INFO: enter test to run
[14:23:50.029] INFO: test: HighRate no parameter change
[14:23:50.029] INFO: running: highrate
[14:23:50.030] INFO: ----------------------------------------------------------------------
[14:23:50.030] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:23:50.030] INFO: ----------------------------------------------------------------------
[14:23:50.172] INFO: Expecting 768 events.
[14:23:51.305] INFO: 768 events read in total (418ms).
[14:23:51.306] INFO: Test took 1268ms.
[14:23:52.109] INFO: Expecting 41600 events.
[14:23:55.156] INFO: 41600 events read in total (2520ms).
[14:23:55.157] INFO: Test took 3843ms.
[14:23:55.192] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:55.921] INFO: Expecting 41600 events.
[14:23:59.108] INFO: 41600 events read in total (2661ms).
[14:23:59.110] INFO: Test took 3901ms.
[14:23:59.146] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:59.869] INFO: Expecting 41600 events.
[14:24:03.114] INFO: 41600 events read in total (2719ms).
[14:24:03.115] INFO: Test took 3950ms.
[14:24:03.152] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:03.870] INFO: Expecting 41600 events.
[14:24:07.124] INFO: 41600 events read in total (2727ms).
[14:24:07.125] INFO: Test took 3956ms.
[14:24:07.161] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:07.880] INFO: Expecting 41600 events.
[14:24:11.135] INFO: 41600 events read in total (2728ms).
[14:24:11.136] INFO: Test took 3956ms.
[14:24:11.170] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:11.894] INFO: Expecting 41600 events.
[14:24:15.151] INFO: 41600 events read in total (2730ms).
[14:24:15.152] INFO: Test took 3963ms.
[14:24:15.187] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:15.908] INFO: Expecting 41600 events.
[14:24:19.162] INFO: 41600 events read in total (2727ms).
[14:24:19.163] INFO: Test took 3956ms.
[14:24:19.198] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:19.921] INFO: Expecting 41600 events.
[14:24:23.202] INFO: 41600 events read in total (2754ms).
[14:24:23.203] INFO: Test took 3987ms.
[14:24:23.238] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:23.956] INFO: Expecting 41600 events.
[14:24:27.215] INFO: 41600 events read in total (2732ms).
[14:24:27.216] INFO: Test took 3960ms.
[14:24:27.251] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:27.974] INFO: Expecting 41600 events.
[14:24:31.223] INFO: 41600 events read in total (2722ms).
[14:24:31.223] INFO: Test took 3954ms.
[14:24:31.260] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:31.982] INFO: Expecting 41600 events.
[14:24:35.242] INFO: 41600 events read in total (2733ms).
[14:24:35.243] INFO: Test took 3965ms.
[14:24:35.279] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:35.001] INFO: Expecting 41600 events.
[14:24:39.271] INFO: 41600 events read in total (2743ms).
[14:24:39.272] INFO: Test took 3974ms.
[14:24:39.308] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:40.027] INFO: Expecting 41600 events.
[14:24:43.271] INFO: 41600 events read in total (2717ms).
[14:24:43.271] INFO: Test took 3944ms.
[14:24:43.308] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:44.030] INFO: Expecting 41600 events.
[14:24:47.292] INFO: 41600 events read in total (2735ms).
[14:24:47.293] INFO: Test took 3966ms.
[14:24:47.328] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:48.051] INFO: Expecting 41600 events.
[14:24:51.309] INFO: 41600 events read in total (2731ms).
[14:24:51.310] INFO: Test took 3963ms.
[14:24:51.345] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:52.069] INFO: Expecting 41600 events.
[14:24:55.327] INFO: 41600 events read in total (2732ms).
[14:24:55.328] INFO: Test took 3965ms.
[14:24:55.363] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:56.083] INFO: Expecting 41600 events.
[14:24:59.352] INFO: 41600 events read in total (2742ms).
[14:24:59.353] INFO: Test took 3970ms.
[14:24:59.389] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:00.112] INFO: Expecting 41600 events.
[14:25:03.370] INFO: 41600 events read in total (2731ms).
[14:25:03.371] INFO: Test took 3964ms.
[14:25:03.406] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:04.128] INFO: Expecting 41600 events.
[14:25:07.376] INFO: 41600 events read in total (2721ms).
[14:25:07.377] INFO: Test took 3953ms.
[14:25:07.413] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:08.133] INFO: Expecting 41600 events.
[14:25:11.284] INFO: 41600 events read in total (2624ms).
[14:25:11.285] INFO: Test took 3853ms.
[14:25:11.320] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:11.681] INFO: enter test to run
[14:26:16.596] INFO: test: HighRate no parameter change
[14:26:16.596] INFO: running: highrate
[14:26:16.597] INFO: ----------------------------------------------------------------------
[14:26:16.597] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:26:16.597] INFO: ----------------------------------------------------------------------
[14:26:17.211] INFO: Expecting 208000 events.
[14:26:29.150] INFO: 208000 events read in total (11412ms).
[14:26:29.153] INFO: Test took 12547ms.
[14:26:29.304] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:29.558] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:26:29.558] INFO: number of red-efficiency pixels: 77 37 90 129 196 129 126 143 111 142 102 101 93 68 31 35
[14:26:29.559] INFO: number of X-ray hits detected: 69598 39912 70149 109348 119172 116782 118949 89251 87066 111793 100132 85990 92197 54260 22998 26235
[14:26:29.559] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:26:29.559] INFO: number of Vcal hits detected: 207923 207963 207907 207864 207801 207868 207858 207855 207887 207856 207897 207897 207883 207928 207969 207965
[14:26:29.559] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[14:26:29.559] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 99.9 100.0 100.0 100.0
[14:26:29.559] INFO: X-ray hit rate [MHz/cm2]: 20.4 11.7 20.6 32.1 34.9 34.2 34.9 26.2 25.5 32.8 29.3 25.2 27.0 15.9 6.7 7.7
[14:26:29.559] INFO: PixTestHighRate::doXPixelAlive() done
[14:26:29.605] INFO: PixTest:: pg_setup set to default.
[14:26:29.617] INFO: enter test to run
[14:26:48.499] INFO: test: HighRate no parameter change
[14:26:48.499] INFO: running: highrate
[14:26:48.501] INFO: ----------------------------------------------------------------------
[14:26:48.501] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:26:48.501] INFO: ----------------------------------------------------------------------
[14:26:49.117] INFO: Expecting 208000 events.
[14:27:02.794] INFO: 208000 events read in total (13151ms).
[14:27:02.799] INFO: Test took 14289ms.
[14:27:03.095] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:03.398] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:27:03.398] INFO: number of red-efficiency pixels: 202 99 279 352 514 362 413 396 319 479 345 337 298 195 46 77
[14:27:03.398] INFO: number of X-ray hits detected: 138491 80304 139784 219189 238003 235205 238529 178988 172657 223851 199307 173062 183552 108583 45867 52169
[14:27:03.398] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:27:03.398] INFO: number of Vcal hits detected: 207781 207900 207701 207595 207436 207612 207537 207573 207664 207491 207636 207634 207658 207794 207952 207922
[14:27:03.398] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[14:27:03.398] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[14:27:03.399] INFO: X-ray hit rate [MHz/cm2]: 40.6 23.5 41.0 64.2 69.8 68.9 69.9 52.5 50.6 65.6 58.4 50.7 53.8 31.8 13.4 15.3
[14:27:03.399] INFO: PixTestHighRate::doXPixelAlive() done
[14:27:03.447] INFO: PixTest:: pg_setup set to default.
[14:27:03.459] INFO: enter test to run
[14:27:49.771] INFO: test: HighRate no parameter change
[14:27:49.771] INFO: running: highrate
[14:27:49.772] INFO: ----------------------------------------------------------------------
[14:27:49.772] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:27:49.772] INFO: ----------------------------------------------------------------------
[14:27:50.390] INFO: Expecting 208000 events.
[14:28:06.032] INFO: 208000 events read in total (15115ms).
[14:28:06.040] INFO: Test took 16258ms.
[14:28:06.492] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:06.845] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:28:06.845] INFO: number of red-efficiency pixels: 393 240 662 800 1262 930 959 918 733 1126 875 746 723 441 97 104
[14:28:06.845] INFO: number of X-ray hits detected: 211236 123179 214005 335351 362299 358047 363001 272740 264527 341757 304118 264972 280476 166613 70435 80279
[14:28:06.845] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:28:06.845] INFO: number of Vcal hits detected: 207539 207738 207142 207057 206369 206855 206803 206807 207139 206548 206948 207114 207156 207472 207900 207894
[14:28:06.845] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.6 99.6 99.3 99.5 99.5 99.5 99.6 99.4 99.5 99.6 99.6 99.8 100.0 100.0
[14:28:06.845] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.5 99.2 99.4 99.4 99.4 99.6 99.3 99.5 99.6 99.6 99.7 100.0 99.9
[14:28:06.845] INFO: X-ray hit rate [MHz/cm2]: 61.9 36.1 62.7 98.3 106.2 104.9 106.4 79.9 77.5 100.2 89.1 77.7 82.2 48.8 20.6 23.5
[14:28:06.845] INFO: PixTestHighRate::doXPixelAlive() done
[14:28:06.897] INFO: PixTest:: pg_setup set to default.
[14:28:06.913] INFO: enter test to run
[14:28:21.531] INFO: test: exit no parameter change
[14:28:21.906] QUIET: Connection to board 33 closed.
[14:28:21.907] INFO: pXar: this is the end, my friend