[10:51:19.413] INFO: *** Welcome to pxar ***
[10:51:19.413] INFO: *** Today: 2016/05/26
[10:51:19.772] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:51:19.772] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//dacParameters35_C15.dat
[10:51:19.830] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:51:19.830] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//defaultMaskFile.dat
[10:51:19.834] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C15.dat
[10:51:19.926] INFO: clk: 4
[10:51:19.926] INFO: ctr: 4
[10:51:19.926] INFO: sda: 19
[10:51:19.926] INFO: tin: 9
[10:51:19.926] INFO: level: 15
[10:51:19.926] INFO: triggerdelay: 0
[10:51:19.926] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:51:19.926] INFO: Log level: INFO
[10:51:19.942] QUIET: Connection to board DTB_WREKRL opened.
[10:51:19.945] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[10:51:19.948] INFO: RPC call hashes of host and DTB match: 398089610
[10:51:21.476] INFO: DUT info:
[10:51:21.476] INFO: The DUT currently contains the following objects:
[10:51:21.476] INFO: 2 TBM Cores tbm08c (2 ON)
[10:51:21.476] INFO: TBM Core alpha (0): 7 registers set
[10:51:21.477] INFO: TBM Core beta (1): 7 registers set
[10:51:21.477] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:51:21.477] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.477] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.477] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.477] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.477] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.477] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.477] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.477] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.477] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.477] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.477] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.477] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.477] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.477] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.477] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.477] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:21.881] INFO: enter 'restricted' command line mode
[10:51:21.881] INFO: enter test to run
[10:51:49.268] INFO: test: PixelAlive no parameter change
[10:51:49.268] INFO: running: pixelalive
[10:51:49.277] INFO: ----------------------------------------------------------------------
[10:51:49.277] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:51:49.277] INFO: ----------------------------------------------------------------------
[10:51:49.596] INFO: Expecting 41600 events.
[10:51:53.980] INFO: 41600 events read in total (3666ms).
[10:51:54.149] INFO: Test took 4870ms.
[10:51:54.160] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:54.434] INFO: PixTestAlive::aliveTest() done
[10:51:54.434] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 2 0 0 0 0
[10:51:54.486] INFO: enter test to run
[10:52:24.755] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:52:24.755] INFO: running: highrate
[10:52:24.756] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:52:24.988] INFO: ----------------------------------------------------------------------
[10:52:24.988] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:52:24.988] INFO: ----------------------------------------------------------------------
[10:52:24.988] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:52:24.988] INFO: edge/corner pixel THR is adjusted
[10:52:24.988] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:52:25.945] INFO: Collecting data for 5 seconds...
[10:52:30.964] INFO: Done with hot pixel readout
[10:52:44.107] INFO: PixTest:: pg_setup set to default.
[10:52:44.107] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:52:44.107] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:52:44.136] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:52:44.136] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:52:44.136] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:52:44.137] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:52:44.137] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:52:44.137] INFO: 256 hot pixels found in step 0
[10:52:45.132] INFO: Collecting data for 5 seconds...
[10:52:50.149] INFO: Done with hot pixel readout
[10:53:02.188] INFO: PixTest:: pg_setup set to default.
[10:53:02.189] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:02.189] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:02.189] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:02.189] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:02.189] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:02.189] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:02.189] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:02.189] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:02.189] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:02.189] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:02.189] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:02.189] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:02.189] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:02.190] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:02.190] INFO: 240 hot pixels found in step 1
[10:53:03.196] INFO: Collecting data for 5 seconds...
[10:53:08.215] INFO: Done with hot pixel readout
[10:53:20.735] INFO: PixTest:: pg_setup set to default.
[10:53:20.735] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:20.735] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:20.735] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:20.735] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:20.735] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:20.735] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:20.735] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:20.736] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:20.736] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:20.736] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:20.736] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:20.736] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:20.736] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:20.736] INFO: 199 hot pixels found in step 2
[10:53:21.738] INFO: Collecting data for 5 seconds...
[10:53:26.756] INFO: Done with hot pixel readout
[10:53:39.513] INFO: PixTest:: pg_setup set to default.
[10:53:39.513] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.513] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.513] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.514] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.514] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.514] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.514] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.514] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.514] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.514] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.514] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.514] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.514] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.514] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.514] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.515] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:39.515] INFO: 203 hot pixels found in step 3
[10:53:40.517] INFO: Collecting data for 5 seconds...
[10:53:45.535] INFO: Done with hot pixel readout
[10:53:58.310] INFO: PixTest:: pg_setup set to default.
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.310] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.311] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.311] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:53:58.311] INFO: 192 hot pixels found in step 4
[10:53:59.313] INFO: Collecting data for 5 seconds...
[10:54:04.330] INFO: Done with hot pixel readout
[10:54:17.383] INFO: PixTest:: pg_setup set to default.
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.384] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.385] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:17.385] INFO: 160 hot pixels found in step 5
[10:54:18.387] INFO: Collecting data for 5 seconds...
[10:54:23.404] INFO: Done with hot pixel readout
[10:54:36.403] INFO: PixTest:: pg_setup set to default.
[10:54:36.403] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.403] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.403] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.403] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.403] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.403] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.405] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:36.405] INFO: 135 hot pixels found in step 6
[10:54:37.408] INFO: Collecting data for 5 seconds...
[10:54:42.425] INFO: Done with hot pixel readout
[10:54:55.179] INFO: PixTest:: pg_setup set to default.
[10:54:55.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.179] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.180] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.180] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.180] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.180] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.180] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.180] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.180] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.180] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.180] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.180] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.180] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.180] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.180] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:54:55.180] INFO: 130 hot pixels found in step 7
[10:54:56.182] INFO: Collecting data for 5 seconds...
[10:55:01.199] INFO: Done with hot pixel readout
[10:55:14.087] INFO: PixTest:: pg_setup set to default.
[10:55:14.087] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.088] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.089] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:14.089] INFO: 126 hot pixels found in step 8
[10:55:15.089] INFO: Collecting data for 5 seconds...
[10:55:20.105] INFO: Done with hot pixel readout
[10:55:32.917] INFO: PixTest:: pg_setup set to default.
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.917] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.918] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.918] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.918] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.918] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.918] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.918] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:32.918] INFO: 133 hot pixels found in step 9
[10:55:33.918] INFO: Collecting data for 5 seconds...
[10:55:38.934] INFO: Done with hot pixel readout
[10:55:51.841] INFO: PixTest:: pg_setup set to default.
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.841] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.842] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.842] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.842] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.842] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:55:51.842] INFO: 147 hot pixels found in step 10
[10:55:52.842] INFO: Collecting data for 5 seconds...
[10:55:57.859] INFO: Done with hot pixel readout
[10:56:10.739] INFO: PixTest:: pg_setup set to default.
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.739] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.740] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.740] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.740] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.740] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.740] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.740] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:10.740] INFO: 124 hot pixels found in step 11
[10:56:11.740] INFO: Collecting data for 5 seconds...
[10:56:16.757] INFO: Done with hot pixel readout
[10:56:29.674] INFO: PixTest:: pg_setup set to default.
[10:56:29.674] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.674] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.674] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.674] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.674] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.674] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.674] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.674] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.674] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.674] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.674] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.675] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:29.676] INFO: 122 hot pixels found in step 12
[10:56:30.675] INFO: Collecting data for 5 seconds...
[10:56:35.692] INFO: Done with hot pixel readout
[10:56:48.581] INFO: PixTest:: pg_setup set to default.
[10:56:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:56:48.582] INFO: 109 hot pixels found in step 13
[10:56:49.583] INFO: Collecting data for 5 seconds...
[10:56:54.600] INFO: Done with hot pixel readout
[10:57:07.495] INFO: PixTest:: pg_setup set to default.
[10:57:07.495] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.495] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.495] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.495] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.495] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.495] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.495] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.495] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.495] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.495] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.495] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.495] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.495] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.495] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.495] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.496] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:07.497] INFO: 116 hot pixels found in step 14
[10:57:07.541] INFO: 116 hot pixels could not be trimmed and have been masked.
[10:57:07.545] INFO: PixTest::trimHotPixels() done
[10:57:07.545] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C0.dat
[10:57:07.551] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C1.dat
[10:57:07.557] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C2.dat
[10:57:07.564] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C3.dat
[10:57:07.569] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C4.dat
[10:57:07.574] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C5.dat
[10:57:07.579] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C6.dat
[10:57:07.585] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C7.dat
[10:57:07.590] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C8.dat
[10:57:07.595] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C9.dat
[10:57:07.600] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C10.dat
[10:57:07.606] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C11.dat
[10:57:07.611] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C12.dat
[10:57:07.616] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C13.dat
[10:57:07.621] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C14.dat
[10:57:07.627] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C15.dat
[10:57:07.632] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//defaultMaskFile.dat
[10:57:07.642] INFO: enter test to run
[10:57:47.345] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:57:47.345] INFO: running: highrate
[10:57:47.350] INFO: ----------------------------------------------------------------------
[10:57:47.350] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:57:47.350] INFO: ----------------------------------------------------------------------
[10:57:47.350] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:57:47.350] INFO: edge/corner pixel THR is adjusted
[10:57:47.350] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:57:48.309] INFO: Collecting data for 1 seconds...
[10:57:49.312] INFO: Done with hot pixel readout
[10:57:53.994] INFO: PixTest:: pg_setup set to default.
[10:57:53.995] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:57:53.995] INFO: 1 hot pixels found in step 0
[10:57:53.001] INFO: 1 hot pixels could not be trimmed and have been masked.
[10:57:54.065] INFO: PixTest::trimHotPixels() done
[10:57:54.065] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C0.dat
[10:57:54.075] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C1.dat
[10:57:54.080] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C2.dat
[10:57:54.085] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C3.dat
[10:57:54.090] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C4.dat
[10:57:54.096] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C5.dat
[10:57:54.101] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C6.dat
[10:57:54.106] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C7.dat
[10:57:54.111] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C8.dat
[10:57:54.117] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C9.dat
[10:57:54.122] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C10.dat
[10:57:54.127] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C11.dat
[10:57:54.132] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C12.dat
[10:57:54.137] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C13.dat
[10:57:54.143] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C14.dat
[10:57:54.148] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C15.dat
[10:57:54.153] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//defaultMaskFile.dat
[10:57:54.163] INFO: enter test to run
[10:58:36.113] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:58:36.113] INFO: running: xray
[10:58:36.137] INFO: ----------------------------------------------------------------------
[10:58:36.137] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:58:36.137] INFO: ----------------------------------------------------------------------
[10:58:37.101] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:58:47.484] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[10:59:17.127] INFO: Resuming triggers.
[10:59:27.517] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[10:59:57.210] INFO: Resuming triggers.
[11:00:07.595] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[11:00:36.990] INFO: Resuming triggers.
[11:00:47.377] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[11:01:17.094] INFO: Resuming triggers.
[11:01:27.480] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[11:01:57.715] INFO: Resuming triggers.
[11:02:08.102] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[11:02:38.062] INFO: Resuming triggers.
[11:02:48.450] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[11:03:17.003] INFO: Resuming triggers.
[11:03:28.386] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[11:03:58.590] INFO: Resuming triggers.
[11:04:08.971] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[11:04:39.114] INFO: Resuming triggers.
[11:04:45.998] INFO: data taking finished, elapsed time: 100 seconds.
[11:05:06.052] INFO: PixTest:: pg_setup set to default.
[11:05:06.055] INFO: PixTestXray::doPhRun() done
[11:05:06.197] INFO: enter test to run
[11:05:53.037] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:05:53.037] INFO: running: xray
[11:05:53.039] INFO: ----------------------------------------------------------------------
[11:05:53.039] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:05:53.039] INFO: ----------------------------------------------------------------------
[11:05:54.011] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:05:59.488] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[11:06:29.679] INFO: Resuming triggers.
[11:06:35.156] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[11:07:05.319] INFO: Resuming triggers.
[11:07:10.799] INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[11:07:40.964] INFO: Resuming triggers.
[11:07:46.446] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[11:08:16.652] INFO: Resuming triggers.
[11:08:22.134] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[11:08:52.270] INFO: Resuming triggers.
[11:08:57.751] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[11:09:27.916] INFO: Resuming triggers.
[11:09:33.398] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[11:10:03.495] INFO: Resuming triggers.
[11:10:08.975] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[11:10:38.803] INFO: Resuming triggers.
[11:10:44.287] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[11:11:14.439] INFO: Resuming triggers.
[11:11:19.922] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[11:11:50.178] INFO: Resuming triggers.
[11:11:55.655] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[11:12:25.624] INFO: Resuming triggers.
[11:12:31.101] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[11:13:02.094] INFO: Resuming triggers.
[11:13:07.577] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[11:13:38.478] INFO: Resuming triggers.
[11:13:43.956] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[11:13:48.068] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 4 ROCs were found
[11:13:48.097] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[11:13:48.097] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (170) != TBM ID (19)
[11:13:48.097] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (2) != Token Chain Length (8)
[11:13:48.097] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (20) != TBM ID (170)
[11:13:48.097] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[11:13:48.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ac 80b1 43c8 43c8 281 2a46 43c8 489 2262 4c0 244a 509 204e 43ca 43c8 15 246e e0 2a4e 43c8 43c8 43c8 e000 c000
[11:13:48.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a6 8000 4388 610 2846 802 2222 4388 492 206e 850 2662 4388 641 242a 4388 40b 2068 4388 350 2a44 4388 4388 4388 e000 c000
[11:13:48.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a7 8040 4388 309 2a8c 4388 60b 244f 4388 99 284c 4388 351 2a65 4388 45 286a 89 2a46 495 2664 4388 0 2444 202 2469 4388 4388 e000 c000
[11:13:48.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a8 80b1 4388 515 284a 50c 224a 4388 858 2a2e 860 264e 4388 c 2862 4c5 2848 4388 120 2266 4388 4388 21b 226e 4388 4388 25a 2a44 420 2a64 e000 c000
[11:13:48.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a9 80c0 4388 4388 353 244e 343 284b 442 284e 55a 244e 53c8 1714 2666 80a 2f68 70 2842 d04 2d9b 85 2097 2090 5b3 43c8 31d 224e 43c8 359 204c 43c8 2e0 2a43 e000 c000
[11:13:48.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1aa 8000 43c8 75d 2042 43c8 2 224a 43c8 8d 2466 2dc 224e 855 2443 849 224e 43c8 98 2285 4cc 2846 43c8 209 2645 209 2a49 64a 2262 6d9 2040 43c8 211 244f 441 2062 440 282f 43c8 651 224a 43c8 e000 c000
[11:13:48.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ab 8040 43c8 158 224a 43c8 ca 204f 48d 206a 50c 244f 755 2442 43c8 60c 244e 612 2a4f 81a 2462 43c8 21d 2048 43c8 61 246a 43c8 d 2868 b 226f 43c8 4d9 2848 43c8 61d 224f e000 c000
[11:13:48.097] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[11:13:48.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ad 80c0 43c8 10 2a2e 552 2046 43c8 51 2840 201 2a4e 43c9 710 242e 43c8 690 2648 43ca 2d0 2444 34a 224f 819 204f 43c8 489 224f 43c8 682 208c 43c8 91 2862 2ca 2a6b 4db 206f 4e2 2a66 651 2866 684 282e 719 266e e000 c000
[11:13:48.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a8 80b1 4388 113 2468 112 2060 2dc 224e 4388 4388 448 2a4c 4e1 2486 689 206e 4388 4388 14 244e 2d3 2a66 60c 2a63 4388 c 202e 5c 264e 208 2046 449 266e 749 222a 4388 80c 2480 4388 30a 2082 485 2a48 e000 c000
[11:13:48.098] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a0a9 80c0 4388 35a 2866 4388 4388 4388 b 286e 14c 2666 12d0 362e 43c8 aa
[11:13:48.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a013 8000 331 2849 251 28dd 431 2a5b 31a 2448 43c8 2441 a49 227a 859 2443 702 43c8 e000 c000
[11:13:48.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0aa 8000 43c8 213 2a4c 49a 2a4e 4db 2a62 43c8 358 2a2e 43ca 29b 284f 55b 2866 43c8 d9 286a 4d0 2240 75c 2468 43c8 5 2a66 694 2088 759 2068 43c8 b 2466 5c 2262 10a 2862 705 2062 43c8 30d 286f 43c8 443 2046 559 246f 762 2a62 e000 c000
[11:13:48.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ab 8040 43c8 d4 2a68 718 2846 43c8 45d 2646 43c8 43c8 481 2842 43c8 75d 2466 43c8 119 2046 43c8 48 264a 14a 206a 43c8 58 2840 e000 c000
[11:13:48.098] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ac 80b1 43c8 43c8 da 2a2e 418 244a 43c8 e1 2466 51a 2a4f 743 2463 43c8 43c8 43c8 a 2a6c 35b 2a6e 64b 2082 722 2a6a 43c8 43c8 34d 264f e000 c000
[11:14:14.978] INFO: Resuming triggers.
[11:14:20.455] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[11:14:51.207] INFO: Resuming triggers.
[11:14:56.684] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[11:15:28.085] INFO: Resuming triggers.
[11:15:33.563] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[11:16:03.600] INFO: Resuming triggers.
[11:16:09.083] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[11:16:39.493] INFO: Resuming triggers.
[11:16:41.192] INFO: data taking finished, elapsed time: 100 seconds.
[11:16:50.890] INFO: PixTest:: pg_setup set to default.
[11:16:50.893] INFO: PixTestXray::doPhRun() done
[11:16:51.043] INFO: enter test to run
[11:17:14.241] INFO: test: timing no parameter change
[11:17:14.241] INFO: running: timing
[11:17:14.266] INFO: ######################################################################
[11:17:14.266] INFO: PixTestTiming::doTest()
[11:17:14.275] INFO: ######################################################################
[11:17:14.275] INFO: ----------------------------------------------------------------------
[11:17:14.275] INFO: PixTestTiming::TBMPhaseScan()
[11:17:14.275] INFO: ----------------------------------------------------------------------
[11:22:56.325] INFO: TBM Phase Settings: 240
[11:22:56.325] INFO: 400MHz Phase: 4
[11:22:56.325] INFO: 160MHz Phase: 7
[11:22:56.325] INFO: Functional Phase Area: 5
[11:22:56.336] INFO: Test took 342061 ms.
[11:22:56.336] INFO: PixTestTiming::TBMPhaseScan() done.
[11:22:56.336] INFO: ----------------------------------------------------------------------
[11:22:56.336] INFO: PixTestTiming::ROCDelayScan()
[11:22:56.336] INFO: ----------------------------------------------------------------------
[11:24:52.614] INFO: ROC Delay Settings: 228
[11:24:52.614] INFO: ROC Header-Trailer/Token Delay: 11
[11:24:52.614] INFO: ROC Port 0 Delay: 4
[11:24:52.614] INFO: ROC Port 1 Delay: 4
[11:24:52.614] INFO: Functional ROC Area: 3
[11:24:52.617] INFO: Test took 116281 ms.
[11:24:52.617] INFO: PixTestTiming::ROCDelayScan() done.
[11:24:52.618] INFO: ----------------------------------------------------------------------
[11:24:52.618] INFO: PixTestTiming::TimingTest()
[11:24:52.618] INFO: ----------------------------------------------------------------------
[11:25:08.911] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:24.007] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:39.162] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:54.178] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:09.358] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:24.549] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:39.654] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:54.865] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:10.028] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:25.163] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:25.537] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:25.550] INFO: ----------------------------------------------------------------------
[11:27:25.550] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[11:27:25.550] INFO: ----------------------------------------------------------------------
[11:27:25.550] INFO: ----------------------------------------------------------------------
[11:27:25.550] INFO: Read back bit status: 1
[11:27:25.550] INFO: ----------------------------------------------------------------------
[11:27:25.550] INFO: ----------------------------------------------------------------------
[11:27:25.550] INFO: Timings are good!
[11:27:25.550] INFO: ----------------------------------------------------------------------
[11:27:25.550] INFO: Test took 152933 ms.
[11:27:25.550] INFO: PixTestTiming::TimingTest() done.
[11:27:25.568] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//tbmParameters_C0a.dat
[11:27:25.568] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Y-M-NJ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:27:25.569] INFO: PixTestTiming::doTest took 611306 ms.
[11:27:25.569] INFO: PixTestTiming::doTest() done
[11:27:25.569] INFO: Write out TBMPhaseScan_0_V0
[11:27:25.569] INFO: Write out TBMPhaseScan_1_V0
[11:27:25.569] INFO: Write out CombinedTBMPhaseScan_V0
[11:27:25.602] INFO: Write out ROCDelayScan3_V0
[11:27:25.603] INFO: enter test to run
[11:28:01.748] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:28:01.748] INFO: running: xray
[11:28:01.749] INFO: ----------------------------------------------------------------------
[11:28:01.749] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:28:01.749] INFO: ----------------------------------------------------------------------
[11:28:02.713] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:28:08.197] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[11:28:38.313] INFO: Resuming triggers.
[11:28:43.798] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[11:29:13.966] INFO: Resuming triggers.
[11:29:19.451] INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[11:29:49.586] INFO: Resuming triggers.
[11:29:55.072] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[11:30:24.855] INFO: Resuming triggers.
[11:30:30.338] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[11:31:00.102] INFO: Resuming triggers.
[11:31:05.584] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[11:31:35.362] INFO: Resuming triggers.
[11:31:40.846] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[11:32:10.587] INFO: Resuming triggers.
[11:32:16.071] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[11:32:45.659] INFO: Resuming triggers.
[11:32:51.143] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[11:33:20.684] INFO: Resuming triggers.
[11:33:26.168] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[11:33:55.996] INFO: Resuming triggers.
[11:34:01.477] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[11:34:30.414] INFO: Resuming triggers.
[11:34:35.896] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[11:35:05.681] INFO: Resuming triggers.
[11:35:11.165] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[11:35:41.466] INFO: Resuming triggers.
[11:35:46.947] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[11:36:16.788] INFO: Resuming triggers.
[11:36:22.272] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[11:36:51.778] INFO: Resuming triggers.
[11:36:57.261] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[11:37:26.943] INFO: Resuming triggers.
[11:37:32.430] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[11:38:02.703] INFO: Resuming triggers.
[11:38:08.187] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[11:38:37.984] INFO: Resuming triggers.
[11:38:39.603] INFO: data taking finished, elapsed time: 100 seconds.
[11:38:48.784] INFO: PixTest:: pg_setup set to default.
[11:38:48.787] INFO: PixTestXray::doPhRun() done
[11:38:48.938] INFO: enter test to run
[11:39:30.575] INFO: test: HighRate no parameter change
[11:39:30.575] INFO: running: highrate
[11:39:30.576] INFO: ----------------------------------------------------------------------
[11:39:30.576] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:39:30.576] INFO: ----------------------------------------------------------------------
[11:39:30.728] INFO: Expecting 768 events.
[11:39:31.861] INFO: 768 events read in total (418ms).
[11:39:31.862] INFO: Test took 1269ms.
[11:39:32.664] INFO: Expecting 41600 events.
[11:39:35.765] INFO: 41600 events read in total (2574ms).
[11:39:35.766] INFO: Test took 3882ms.
[11:39:35.810] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:36.516] INFO: Expecting 41600 events.
[11:39:39.679] INFO: 41600 events read in total (2636ms).
[11:39:39.680] INFO: Test took 3848ms.
[11:39:39.724] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:40.428] INFO: Expecting 41600 events.
[11:39:43.737] INFO: 41600 events read in total (2782ms).
[11:39:43.738] INFO: Test took 3992ms.
[11:39:43.782] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:44.488] INFO: Expecting 41600 events.
[11:39:47.790] INFO: 41600 events read in total (2775ms).
[11:39:47.791] INFO: Test took 3988ms.
[11:39:47.836] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:48.539] INFO: Expecting 41600 events.
[11:39:51.839] INFO: 41600 events read in total (2773ms).
[11:39:51.840] INFO: Test took 3982ms.
[11:39:51.883] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:52.592] INFO: Expecting 41600 events.
[11:39:55.882] INFO: 41600 events read in total (2763ms).
[11:39:55.883] INFO: Test took 3978ms.
[11:39:55.928] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:56.634] INFO: Expecting 41600 events.
[11:39:59.969] INFO: 41600 events read in total (2808ms).
[11:39:59.970] INFO: Test took 4021ms.
[11:40:00.014] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:00.717] INFO: Expecting 41600 events.
[11:40:04.051] INFO: 41600 events read in total (2807ms).
[11:40:04.053] INFO: Test took 4017ms.
[11:40:04.096] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:04.802] INFO: Expecting 41600 events.
[11:40:08.118] INFO: 41600 events read in total (2789ms).
[11:40:08.119] INFO: Test took 4001ms.
[11:40:08.162] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:08.866] INFO: Expecting 41600 events.
[11:40:12.174] INFO: 41600 events read in total (2782ms).
[11:40:12.176] INFO: Test took 3991ms.
[11:40:12.219] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:12.923] INFO: Expecting 41600 events.
[11:40:16.246] INFO: 41600 events read in total (2796ms).
[11:40:16.248] INFO: Test took 4008ms.
[11:40:16.292] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:16.990] INFO: Expecting 41600 events.
[11:40:20.291] INFO: 41600 events read in total (2774ms).
[11:40:20.292] INFO: Test took 3978ms.
[11:40:20.337] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:21.042] INFO: Expecting 41600 events.
[11:40:24.367] INFO: 41600 events read in total (2799ms).
[11:40:24.369] INFO: Test took 4010ms.
[11:40:24.412] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:25.119] INFO: Expecting 41600 events.
[11:40:28.459] INFO: 41600 events read in total (2813ms).
[11:40:28.460] INFO: Test took 4027ms.
[11:40:28.504] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:29.208] INFO: Expecting 41600 events.
[11:40:32.551] INFO: 41600 events read in total (2816ms).
[11:40:32.552] INFO: Test took 4027ms.
[11:40:32.596] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:33.300] INFO: Expecting 41600 events.
[11:40:36.210] INFO: 41600 events read in total (2384ms).
[11:40:36.211] INFO: Test took 3593ms.
[11:40:36.254] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:36.958] INFO: Expecting 41600 events.
[11:40:40.293] INFO: 41600 events read in total (2808ms).
[11:40:40.295] INFO: Test took 4020ms.
[11:40:40.338] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:41.046] INFO: Expecting 41600 events.
[11:40:44.383] INFO: 41600 events read in total (2810ms).
[11:40:44.384] INFO: Test took 4024ms.
[11:40:44.427] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:45.134] INFO: Expecting 41600 events.
[11:40:48.439] INFO: 41600 events read in total (2778ms).
[11:40:48.440] INFO: Test took 3990ms.
[11:40:48.484] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:49.190] INFO: Expecting 41600 events.
[11:40:52.271] INFO: 41600 events read in total (2554ms).
[11:40:52.272] INFO: Test took 3765ms.
[11:40:52.315] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:52.665] INFO: enter test to run
[11:41:23.590] INFO: test: HighRate no parameter change
[11:41:23.590] INFO: running: highrate
[11:41:23.591] INFO: ----------------------------------------------------------------------
[11:41:23.591] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:41:23.591] INFO: ----------------------------------------------------------------------
[11:41:24.210] INFO: Expecting 208000 events.
[11:41:36.643] INFO: 208000 events read in total (11906ms).
[11:41:36.647] INFO: Test took 13046ms.
[11:41:36.837] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:41:37.104] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 2 0 0 0 0
[11:41:37.104] INFO: number of red-efficiency pixels: 91 86 152 230 223 228 263 179 128 153 146 178 142 81 33 36
[11:41:37.104] INFO: number of X-ray hits detected: 82749 61186 94609 148876 160870 157036 163696 111495 92240 125622 124421 116293 113807 66113 29049 33115
[11:41:37.104] 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:41:37.104] INFO: number of Vcal hits detected: 207907 207910 207836 207758 207770 207763 207723 207814 207872 207844 207849 207704 207853 207919 207966 207964
[11:41:37.104] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[11:41:37.104] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[11:41:37.104] INFO: X-ray hit rate [MHz/cm2]: 24.3 17.9 27.7 43.6 47.2 46.0 48.0 32.7 27.0 36.8 36.5 34.1 33.4 19.4 8.5 9.7
[11:41:37.104] INFO: PixTestHighRate::doXPixelAlive() done
[11:41:37.149] INFO: PixTest:: pg_setup set to default.
[11:41:37.159] INFO: enter test to run
[11:42:05.734] INFO: test: HighRate no parameter change
[11:42:05.734] INFO: running: highrate
[11:42:05.735] INFO: ----------------------------------------------------------------------
[11:42:05.735] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:42:05.735] INFO: ----------------------------------------------------------------------
[11:42:06.356] INFO: Expecting 208000 events.
[11:42:21.276] INFO: 208000 events read in total (14393ms).
[11:42:21.283] INFO: Test took 15537ms.
[11:42:21.670] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:21.002] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 2 0 0 0 0
[11:42:21.003] INFO: number of red-efficiency pixels: 339 251 568 934 774 803 977 658 399 482 534 653 474 225 82 91
[11:42:21.003] INFO: number of X-ray hits detected: 171712 126683 197145 307777 332617 325121 340032 232019 192309 260216 258770 239780 235652 137834 60860 69407
[11:42:21.003] 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:42:21.003] INFO: number of Vcal hits detected: 207636 207735 207287 206891 207116 207065 206821 207227 207570 207469 207412 207122 207481 207754 207914 207908
[11:42:21.003] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.6 99.6 99.5 99.7 99.8 99.8 99.7 99.7 99.8 99.9 100.0 100.0
[11:42:21.003] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.5 99.6 99.6 99.4 99.6 99.8 99.7 99.7 99.6 99.8 99.9 100.0 100.0
[11:42:21.003] INFO: X-ray hit rate [MHz/cm2]: 50.3 37.1 57.8 90.2 97.5 95.3 99.7 68.0 56.4 76.3 75.8 70.3 69.1 40.4 17.8 20.3
[11:42:21.003] INFO: PixTestHighRate::doXPixelAlive() done
[11:42:22.048] INFO: PixTest:: pg_setup set to default.
[11:42:22.062] INFO: enter test to run
[11:42:45.085] INFO: test: HighRate no parameter change
[11:42:45.085] INFO: running: highrate
[11:42:45.087] INFO: ----------------------------------------------------------------------
[11:42:45.087] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:42:45.087] INFO: ----------------------------------------------------------------------
[11:42:45.704] INFO: Expecting 208000 events.
[11:43:03.481] INFO: 208000 events read in total (17250ms).
[11:43:03.490] INFO: Test took 18393ms.
[11:43:04.099] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:43:04.499] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 2 0 0 0 0
[11:43:04.499] INFO: number of red-efficiency pixels: 775 568 1214 2223 1937 1850 2221 1458 884 1280 1209 1411 1107 550 146 157
[11:43:04.499] INFO: number of X-ray hits detected: 260091 191931 297937 466269 501655 489808 512941 351384 291182 394693 392728 359706 358846 209751 92212 105133
[11:43:04.499] 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:43:04.499] INFO: number of Vcal hits detected: 207035 207340 205783 204287 205029 205114 204262 205732 206865 206314 206454 205861 206632 207326 207846 207835
[11:43:04.499] INFO: Vcal hit fiducial efficiency (%): 99.6 99.7 99.1 98.4 98.7 98.8 98.4 99.0 99.5 99.3 99.3 99.1 99.4 99.7 99.9 99.9
[11:43:04.499] INFO: Vcal hit overall efficiency (%): 99.5 99.7 98.9 98.2 98.6 98.6 98.2 98.9 99.5 99.2 99.3 99.0 99.3 99.7 99.9 99.9
[11:43:04.499] INFO: X-ray hit rate [MHz/cm2]: 76.2 56.3 87.3 136.7 147.0 143.6 150.3 103.0 85.3 115.7 115.1 105.4 105.2 61.5 27.0 30.8
[11:43:04.499] INFO: PixTestHighRate::doXPixelAlive() done
[11:43:04.547] INFO: PixTest:: pg_setup set to default.
[11:43:04.565] INFO: enter test to run
[11:43:08.717] INFO: test: exit no parameter change
[11:43:09.099] QUIET: Connection to board 33 closed.
[11:43:09.100] INFO: pXar: this is the end, my friend