[08:52:58.613] INFO: *** Welcome to pxar ***
[08:52:58.613] INFO: *** Today: 2016/04/18
[08:52:58.985] INFO: *** Version: v1.9.0-796-gef167-dirty
[08:52:58.985] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//dacParameters_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//dacParameters_C15.dat
[08:52:59.014] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//tbmParameters_C0b.dat
[08:52:59.014] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//defaultMaskFile.dat
[08:52:59.015] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C15.dat
[08:52:59.093] INFO: clk: 4
[08:52:59.093] INFO: ctr: 4
[08:52:59.093] INFO: sda: 19
[08:52:59.093] INFO: tin: 9
[08:52:59.093] INFO: level: 15
[08:52:59.093] INFO: triggerdelay: 0
[08:52:59.093] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[08:52:59.093] INFO: Log level: INFO
[08:52:59.113] QUIET: Connection to board DTB_WREKRL opened.
[08:52:59.117] 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:
------------------------------------------------------
[08:52:59.119] INFO: RPC call hashes of host and DTB match: 398089610
[08:53:00.655] INFO: DUT info:
[08:53:00.655] INFO: The DUT currently contains the following objects:
[08:53:00.655] INFO: 2 TBM Cores tbm08c (2 ON)
[08:53:00.655] INFO: TBM Core alpha (0): 7 registers set
[08:53:00.655] INFO: TBM Core beta (1): 7 registers set
[08:53:00.655] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:53:00.655] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:00.655] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:00.655] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:00.655] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:00.655] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:00.655] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:00.655] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:00.655] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:00.655] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:00.655] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:00.655] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:00.655] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:00.655] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:00.655] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:00.655] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:00.655] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:53:01.058] INFO: enter 'restricted' command line mode
[08:53:01.058] INFO: enter test to run
[08:53:08.370] INFO: test: PixelAlive no parameter change
[08:53:08.370] INFO: running: pixelalive
[08:53:08.379] INFO: ----------------------------------------------------------------------
[08:53:08.379] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:53:08.379] INFO: ----------------------------------------------------------------------
[08:53:08.697] INFO: Expecting 41600 events.
[08:53:13.036] INFO: 41600 events read in total (3621ms).
[08:53:13.201] INFO: Test took 4819ms.
[08:53:13.210] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:53:13.507] INFO: PixTestAlive::aliveTest() done
[08:53:13.507] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:53:13.539] INFO: enter test to run
[08:54:18.081] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[08:54:18.081] INFO: running: highrate
[08:54:18.082] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C15.dat

[08:54:18.085] INFO: ----------------------------------------------------------------------
[08:54:18.085] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[08:54:18.085] INFO: ----------------------------------------------------------------------
[08:54:18.085] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[08:54:18.085] INFO: edge/corner pixel THR is adjusted
[08:54:18.085] INFO: PixTestHighRate::trimHotPixels: step 0...
[08:54:19.044] INFO: Collecting data for 5 seconds...
[08:54:24.061] INFO: Done with hot pixel readout
[08:54:32.874] INFO: PixTest:: pg_setup set to default.
[08:54:32.874] INFO: 0 hot pixels found in step 0
[08:54:32.883] INFO: 0 hot pixels could not be trimmed and have been masked.
[08:54:33.010] INFO: PixTest::trimHotPixels() done
[08:54:33.020] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C0.dat
[08:54:33.025] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C1.dat
[08:54:33.030] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C2.dat
[08:54:33.036] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C3.dat
[08:54:33.041] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C4.dat
[08:54:33.046] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C5.dat
[08:54:33.051] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C6.dat
[08:54:33.056] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C7.dat
[08:54:33.062] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C8.dat
[08:54:33.067] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C9.dat
[08:54:33.072] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C10.dat
[08:54:33.077] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C11.dat
[08:54:33.082] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C12.dat
[08:54:33.087] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C13.dat
[08:54:33.092] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C14.dat
[08:54:33.098] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C15.dat
[08:54:33.103] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//defaultMaskFile.dat
[08:54:33.111] INFO: enter test to run
[08:55:16.745] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[08:55:16.745] INFO: running: highrate
[08:55:16.745] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C15.dat

[08:55:16.748] INFO: ----------------------------------------------------------------------
[08:55:16.749] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[08:55:16.749] INFO: ----------------------------------------------------------------------
[08:55:16.749] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[08:55:16.749] INFO: edge/corner pixel THR is adjusted
[08:55:16.749] INFO: PixTestHighRate::trimHotPixels: step 0...
[08:55:17.706] INFO: Collecting data for 1 seconds...
[08:55:18.710] INFO: Done with hot pixel readout
[08:55:21.023] INFO: PixTest:: pg_setup set to default.
[08:55:21.024] INFO: 0 hot pixels found in step 0
[08:55:21.028] INFO: 0 hot pixels could not be trimmed and have been masked.
[08:55:21.191] INFO: PixTest::trimHotPixels() done
[08:55:21.191] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C0.dat
[08:55:21.199] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C1.dat
[08:55:21.204] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C2.dat
[08:55:21.209] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C3.dat
[08:55:21.214] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C4.dat
[08:55:21.220] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C5.dat
[08:55:21.225] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C6.dat
[08:55:21.230] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C7.dat
[08:55:21.235] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C8.dat
[08:55:21.240] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C9.dat
[08:55:21.246] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C10.dat
[08:55:21.251] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C11.dat
[08:55:21.256] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C12.dat
[08:55:21.261] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C13.dat
[08:55:21.267] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C14.dat
[08:55:21.272] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//trimParameters_C15.dat
[08:55:21.277] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//defaultMaskFile.dat
[08:55:21.286] INFO: enter test to run
[08:55:45.489] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[08:55:45.489] INFO: running: xray
[08:55:45.489] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C15.dat

[08:55:45.489] INFO: ----------------------------------------------------------------------
[08:55:45.489] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:55:45.489] INFO: ----------------------------------------------------------------------
[08:55:46.452] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:56:02.401] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[08:56:29.631] INFO: Resuming triggers.
[08:56:45.580] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[08:57:12.949] INFO: Resuming triggers.
[08:57:28.897] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[08:57:51.267] INFO: Resuming triggers.
[08:58:07.217] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[08:58:34.411] INFO: Resuming triggers.
[08:58:50.361] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[08:59:17.635] INFO: Resuming triggers.
[08:59:33.586] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[09:00:00.984] INFO: Resuming triggers.
[09:00:05.614] INFO: data taking finished, elapsed time: 100 seconds.
[09:00:13.905] INFO: PixTest:: pg_setup set to default.
[09:00:13.908] INFO: PixTestXray::doPhRun() done
[09:00:14.005] INFO: enter test to run
[09:00:49.670] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[09:00:49.670] INFO: running: xray
[09:00:49.671] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C15.dat

[09:00:49.671] INFO: ----------------------------------------------------------------------
[09:00:49.671] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:00:49.671] INFO: ----------------------------------------------------------------------
[09:00:50.640] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:01:04.505] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[09:01:32.258] INFO: Resuming triggers.
[09:01:46.127] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[09:02:13.820] INFO: Resuming triggers.
[09:02:27.688] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[09:02:55.298] INFO: Resuming triggers.
[09:03:09.169] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[09:03:36.816] INFO: Resuming triggers.
[09:03:50.684] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[09:04:18.365] INFO: Resuming triggers.
[09:04:32.230] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[09:04:59.842] INFO: Resuming triggers.
[09:05:13.701] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[09:05:41.438] INFO: Resuming triggers.
[09:05:44.702] INFO: data taking finished, elapsed time: 100 seconds.
[09:05:51.544] INFO: PixTest:: pg_setup set to default.
[09:05:51.547] INFO: PixTestXray::doPhRun() done
[09:05:51.653] INFO: enter test to run
[09:06:20.108] INFO: test: HighRate no parameter change
[09:06:20.108] INFO: running: highrate
[09:06:20.108] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C15.dat

[09:06:20.119] INFO: ----------------------------------------------------------------------
[09:06:20.119] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[09:06:20.119] INFO: ----------------------------------------------------------------------
[09:06:20.274] INFO: Expecting 768 events.
[09:06:21.408] INFO: 768 events read in total (418ms).
[09:06:21.408] INFO: Test took 1268ms.
[09:06:22.212] INFO: Expecting 41600 events.
[09:06:25.040] INFO: 41600 events read in total (2302ms).
[09:06:25.041] INFO: Test took 3627ms.
[09:06:25.050] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:25.822] INFO: Expecting 41600 events.
[09:06:28.782] INFO: 41600 events read in total (2433ms).
[09:06:28.783] INFO: Test took 3723ms.
[09:06:28.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:29.559] INFO: Expecting 41600 events.
[09:06:32.554] INFO: 41600 events read in total (2468ms).
[09:06:32.555] INFO: Test took 3754ms.
[09:06:32.565] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:33.336] INFO: Expecting 41600 events.
[09:06:36.330] INFO: 41600 events read in total (2467ms).
[09:06:36.331] INFO: Test took 3756ms.
[09:06:36.341] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:37.106] INFO: Expecting 41600 events.
[09:06:40.113] INFO: 41600 events read in total (2480ms).
[09:06:40.114] INFO: Test took 3762ms.
[09:06:40.124] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:40.894] INFO: Expecting 41600 events.
[09:06:43.903] INFO: 41600 events read in total (2483ms).
[09:06:43.903] INFO: Test took 3769ms.
[09:06:43.913] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:44.679] INFO: Expecting 41600 events.
[09:06:47.681] INFO: 41600 events read in total (2475ms).
[09:06:47.682] INFO: Test took 3760ms.
[09:06:47.692] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:48.456] INFO: Expecting 41600 events.
[09:06:51.475] INFO: 41600 events read in total (2492ms).
[09:06:51.476] INFO: Test took 3773ms.
[09:06:51.486] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:52.252] INFO: Expecting 41600 events.
[09:06:55.267] INFO: 41600 events read in total (2489ms).
[09:06:55.268] INFO: Test took 3771ms.
[09:06:55.278] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:56.044] INFO: Expecting 41600 events.
[09:06:59.058] INFO: 41600 events read in total (2487ms).
[09:06:59.059] INFO: Test took 3770ms.
[09:06:59.069] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:06:59.833] INFO: Expecting 41600 events.
[09:07:02.842] INFO: 41600 events read in total (2482ms).
[09:07:02.843] INFO: Test took 3764ms.
[09:07:02.852] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:07:03.622] INFO: Expecting 41600 events.
[09:07:06.630] INFO: 41600 events read in total (2481ms).
[09:07:06.631] INFO: Test took 3769ms.
[09:07:06.641] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:07:07.406] INFO: Expecting 41600 events.
[09:07:10.401] INFO: 41600 events read in total (2468ms).
[09:07:10.402] INFO: Test took 3751ms.
[09:07:10.412] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:07:11.182] INFO: Expecting 41600 events.
[09:07:14.194] INFO: 41600 events read in total (2485ms).
[09:07:14.195] INFO: Test took 3772ms.
[09:07:14.206] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:07:14.974] INFO: Expecting 41600 events.
[09:07:17.985] INFO: 41600 events read in total (2484ms).
[09:07:17.985] INFO: Test took 3769ms.
[09:07:17.995] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:07:18.764] INFO: Expecting 41600 events.
[09:07:21.764] INFO: 41600 events read in total (2473ms).
[09:07:21.765] INFO: Test took 3760ms.
[09:07:21.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:07:22.542] INFO: Expecting 41600 events.
[09:07:25.554] INFO: 41600 events read in total (2485ms).
[09:07:25.555] INFO: Test took 3770ms.
[09:07:25.565] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:07:26.329] INFO: Expecting 41600 events.
[09:07:29.334] INFO: 41600 events read in total (2478ms).
[09:07:29.334] INFO: Test took 3759ms.
[09:07:29.345] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:07:30.109] INFO: Expecting 41600 events.
[09:07:33.093] INFO: 41600 events read in total (2457ms).
[09:07:33.093] INFO: Test took 3737ms.
[09:07:33.103] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:07:33.871] INFO: Expecting 41600 events.
[09:07:36.729] INFO: 41600 events read in total (2331ms).
[09:07:36.729] INFO: Test took 3615ms.
[09:07:36.739] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:07:37.143] INFO: enter test to run
[09:07:48.260] INFO: test: HighRate no parameter change
[09:07:48.260] INFO: running: highrate
[09:07:48.260] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C15.dat

[09:07:48.260] INFO: ----------------------------------------------------------------------
[09:07:48.260] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:07:48.260] INFO: ----------------------------------------------------------------------
[09:07:48.875] INFO: Expecting 208000 events.
[09:07:57.942] INFO: 208000 events read in total (8540ms).
[09:07:57.943] INFO: Test took 9675ms.
[09:07:57.969] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:07:58.233] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:07:58.233] INFO: number of red-efficiency pixels: 10 10 5 10 13 24 13 20 25 14 16 8 16 6 4 14
[09:07:58.233] INFO: number of X-ray hits detected: 9376 6910 9556 5949 14832 15468 13231 10933 18039 14145 12914 12377 16350 6593 4359 5402
[09:07:58.233] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:07:58.233] INFO: number of Vcal hits detected: 207990 207990 207995 207990 207987 207976 207987 207980 207975 207986 207984 207992 207984 207994 207996 207986
[09:07:58.234] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[09:07:58.234] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[09:07:58.234] INFO: X-ray hit rate [MHz/cm2]: 2.7 2.0 2.8 1.7 4.3 4.5 3.9 3.2 5.3 4.1 3.8 3.6 4.8 1.9 1.3 1.6
[09:07:58.234] INFO: PixTestHighRate::doXPixelAlive() done
[09:07:58.279] INFO: PixTest:: pg_setup set to default.
[09:07:58.286] INFO: enter test to run
[09:08:16.083] INFO: test: HighRate no parameter change
[09:08:16.084] INFO: running: highrate
[09:08:16.084] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C15.dat

[09:08:16.084] INFO: ----------------------------------------------------------------------
[09:08:16.084] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:08:16.084] INFO: ----------------------------------------------------------------------
[09:08:16.701] INFO: Expecting 208000 events.
[09:08:27.579] INFO: 208000 events read in total (10351ms).
[09:08:27.580] INFO: Test took 11488ms.
[09:08:27.628] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:08:27.849] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:08:27.849] INFO: number of red-efficiency pixels: 17 19 23 14 37 62 46 38 39 36 42 32 46 21 6 25
[09:08:27.849] INFO: number of X-ray hits detected: 20427 15561 21312 13264 32869 34627 28910 23905 39997 31563 29073 27218 36233 14824 9854 12096
[09:08:27.849] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:08:27.849] INFO: number of Vcal hits detected: 207983 207981 207977 207986 207962 207938 207954 207962 207961 207964 207958 207968 207954 207979 207994 207975
[09:08:27.849] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[09:08:27.849] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[09:08:27.849] INFO: X-ray hit rate [MHz/cm2]: 6.0 4.6 6.2 3.9 9.6 10.1 8.5 7.0 11.7 9.3 8.5 8.0 10.6 4.3 2.9 3.5
[09:08:27.849] INFO: PixTestHighRate::doXPixelAlive() done
[09:08:27.896] INFO: PixTest:: pg_setup set to default.
[09:08:27.911] INFO: enter test to run
[09:08:45.587] INFO: test: HighRate no parameter change
[09:08:45.587] INFO: running: highrate
[09:08:45.587] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-35_FPIXTest-17C-FNAL-160415-1126_2016-04-15_11h26m_1460737597/000_FPIXTest_p17//phCalibrationFitErr_C15.dat

[09:08:45.587] INFO: ----------------------------------------------------------------------
[09:08:45.587] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:08:45.588] INFO: ----------------------------------------------------------------------
[09:08:46.200] INFO: Expecting 208000 events.
[09:08:57.315] INFO: 208000 events read in total (10588ms).
[09:08:57.317] INFO: Test took 11722ms.
[09:08:57.389] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:08:57.617] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:08:57.617] INFO: number of red-efficiency pixels: 32 26 32 23 53 72 54 42 75 40 55 33 46 28 11 36
[09:08:57.617] INFO: number of X-ray hits detected: 31223 23597 32747 20264 49947 52278 44793 36501 61042 48015 43932 41237 55193 22212 14752 18175
[09:08:57.617] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:08:57.617] INFO: number of Vcal hits detected: 207968 207973 207968 207977 207946 207928 207946 207957 207925 207959 207945 207967 207954 207972 207989 207964
[09:08:57.617] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[09:08:57.617] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[09:08:57.618] INFO: X-ray hit rate [MHz/cm2]: 9.2 6.9 9.6 5.9 14.6 15.3 13.1 10.7 17.9 14.1 12.9 12.1 16.2 6.5 4.3 5.3
[09:08:57.618] INFO: PixTestHighRate::doXPixelAlive() done
[09:08:57.665] INFO: PixTest:: pg_setup set to default.
[09:08:57.679] INFO: enter test to run
[09:10:14.378] INFO: test: exit no parameter change
[09:10:14.745] QUIET: Connection to board 33 closed.
[09:10:14.745] INFO: pXar: this is the end, my friend