[17:00:36.675] INFO: *** Welcome to pxar ***
[17:00:36.675] INFO: *** Today: 2016/06/03
[17:00:36.692] INFO: *** Version: v1.9.0-813-gb074
[17:00:36.692] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//dacParameters35_C15.dat
[17:00:36.733] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//tbmParameters_C0b.dat
[17:00:36.733] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//defaultMaskFile.dat
[17:00:36.742] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C15.dat
[17:00:36.854] INFO: clk: 4
[17:00:36.854] INFO: ctr: 4
[17:00:36.854] INFO: sda: 19
[17:00:36.854] INFO: tin: 9
[17:00:36.854] INFO: level: 15
[17:00:36.854] INFO: triggerdelay: 0
[17:00:36.854] QUIET: Instanciating API for pxar v1.9.0+813~gb074cf0
[17:00:36.854] INFO: Log level: INFO
[17:00:36.870] QUIET: Connection to board DTB_WREKRL opened.
[17:00:36.873] 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:
------------------------------------------------------
[17:00:36.876] INFO: RPC call hashes of host and DTB match: 398089610
[17:00:38.410] INFO: DUT info:
[17:00:38.410] INFO: The DUT currently contains the following objects:
[17:00:38.410] INFO: 2 TBM Cores tbm08c (2 ON)
[17:00:38.410] INFO: TBM Core alpha (0): 7 registers set
[17:00:38.410] INFO: TBM Core beta (1): 7 registers set
[17:00:38.410] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:00:38.410] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.410] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.410] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.410] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.410] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.410] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.410] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.410] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.410] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.410] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.410] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.410] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.410] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.410] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.410] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.410] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:38.813] INFO: enter 'restricted' command line mode
[17:00:38.813] INFO: enter test to run
[17:00:41.245] INFO: test: PixelAlive no parameter change
[17:00:41.245] INFO: running: pixelalive
[17:00:41.255] INFO: ----------------------------------------------------------------------
[17:00:41.255] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:00:41.255] INFO: ----------------------------------------------------------------------
[17:00:41.584] INFO: Expecting 41600 events.
[17:00:45.962] INFO: 41600 events read in total (3660ms).
[17:00:46.128] INFO: Test took 4870ms.
[17:00:46.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:46.417] INFO: PixTestAlive::aliveTest() done
[17:00:46.417] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 0 3 0
[17:00:46.464] INFO: enter test to run
[17:01:01.941] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[17:01:01.942] INFO: running: highrate
[17:01:01.942] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[17:01:02.184] INFO: ----------------------------------------------------------------------
[17:01:02.184] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[17:01:02.184] INFO: ----------------------------------------------------------------------
[17:01:02.184] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[17:01:02.184] INFO: edge/corner pixel THR is adjusted
[17:01:02.184] INFO: PixTestHighRate::trimHotPixels: step 0...
[17:01:03.144] INFO: Collecting data for 5 seconds...
[17:01:08.163] INFO: Done with hot pixel readout
[17:01:19.961] INFO: PixTest:: pg_setup set to default.
[17:01:19.962] INFO: 4 hot pixels found in step 0
[17:01:20.976] INFO: Collecting data for 5 seconds...
[17:01:25.995] INFO: Done with hot pixel readout
[17:01:37.756] INFO: PixTest:: pg_setup set to default.
[17:01:37.757] INFO: 4 hot pixels found in step 1
[17:01:38.753] INFO: Collecting data for 5 seconds...
[17:01:43.775] INFO: Done with hot pixel readout
[17:01:55.769] INFO: PixTest:: pg_setup set to default.
[17:01:55.770] INFO: 4 hot pixels found in step 2
[17:01:56.770] INFO: Collecting data for 5 seconds...
[17:02:01.789] INFO: Done with hot pixel readout
[17:02:13.613] INFO: PixTest:: pg_setup set to default.
[17:02:13.614] INFO: 3 hot pixels found in step 3
[17:02:14.607] INFO: Collecting data for 5 seconds...
[17:02:19.625] INFO: Done with hot pixel readout
[17:02:31.461] INFO: PixTest:: pg_setup set to default.
[17:02:31.462] INFO: 3 hot pixels found in step 4
[17:02:32.454] INFO: Collecting data for 5 seconds...
[17:02:37.471] INFO: Done with hot pixel readout
[17:02:49.353] INFO: PixTest:: pg_setup set to default.
[17:02:49.354] INFO: 3 hot pixels found in step 5
[17:02:50.348] INFO: Collecting data for 5 seconds...
[17:02:55.365] INFO: Done with hot pixel readout
[17:03:07.207] INFO: PixTest:: pg_setup set to default.
[17:03:07.208] INFO: 0 hot pixels found in step 6
[17:03:07.245] INFO: 0 hot pixels could not be trimmed and have been masked.
[17:03:07.248] INFO: PixTest::trimHotPixels() done
[17:03:07.249] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C0.dat
[17:03:07.254] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C1.dat
[17:03:07.259] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C2.dat
[17:03:07.266] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C3.dat
[17:03:07.271] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C4.dat
[17:03:07.277] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C5.dat
[17:03:07.282] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C6.dat
[17:03:07.287] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C7.dat
[17:03:07.302] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C8.dat
[17:03:07.314] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C9.dat
[17:03:07.321] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C10.dat
[17:03:07.326] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C11.dat
[17:03:07.332] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C12.dat
[17:03:07.337] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C13.dat
[17:03:07.343] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C14.dat
[17:03:07.348] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C15.dat
[17:03:07.353] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//defaultMaskFile.dat
[17:03:07.368] INFO: enter test to run
[17:03:40.356] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[17:03:40.356] INFO: running: highrate
[17:03:40.361] INFO: ----------------------------------------------------------------------
[17:03:40.361] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[17:03:40.361] INFO: ----------------------------------------------------------------------
[17:03:40.361] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[17:03:40.361] INFO: edge/corner pixel THR is adjusted
[17:03:40.361] INFO: PixTestHighRate::trimHotPixels: step 0...
[17:03:41.318] INFO: Collecting data for 1 seconds...
[17:03:42.322] INFO: Done with hot pixel readout
[17:03:46.391] INFO: PixTest:: pg_setup set to default.
[17:03:46.391] INFO: 0 hot pixels found in step 0
[17:03:46.396] INFO: 0 hot pixels could not be trimmed and have been masked.
[17:03:46.485] INFO: PixTest::trimHotPixels() done
[17:03:46.485] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C0.dat
[17:03:46.497] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C1.dat
[17:03:46.502] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C2.dat
[17:03:46.507] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C3.dat
[17:03:46.512] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C4.dat
[17:03:46.518] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C5.dat
[17:03:46.523] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C6.dat
[17:03:46.528] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C7.dat
[17:03:46.533] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C8.dat
[17:03:46.539] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C9.dat
[17:03:46.544] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C10.dat
[17:03:46.549] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C11.dat
[17:03:46.554] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C12.dat
[17:03:46.559] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C13.dat
[17:03:46.565] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C14.dat
[17:03:46.570] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//trimParameters35_C15.dat
[17:03:46.575] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-2-39_FPIXTest-17C-FNAL-160526-1102_2016-05-26_11h02m_1464278543/000_FPIXTest_p17//defaultMaskFile.dat
[17:03:46.585] INFO: enter test to run
[17:04:02.068] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[17:04:02.068] INFO: running: xray
[17:04:02.069] INFO: ----------------------------------------------------------------------
[17:04:02.069] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[17:04:02.069] INFO: ----------------------------------------------------------------------
[17:04:03.033] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[17:04:14.317] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[17:04:43.551] INFO: Resuming triggers.
[17:04:54.843] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[17:05:24.390] INFO: Resuming triggers.
[17:05:35.678] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[17:06:05.027] INFO: Resuming triggers.
[17:06:16.317] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[17:06:45.776] INFO: Resuming triggers.
[17:06:57.064] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[17:07:26.493] INFO: Resuming triggers.
[17:07:37.775] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[17:08:07.190] INFO: Resuming triggers.
[17:08:18.477] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[17:08:47.913] INFO: Resuming triggers.
[17:08:59.195] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[17:09:28.629] INFO: Resuming triggers.
[17:09:38.666] INFO: data taking finished, elapsed time: 100 seconds.
[17:10:05.161] INFO: PixTest:: pg_setup set to default.
[17:10:05.164] INFO: PixTestXray::doPhRun() done
[17:10:05.303] INFO: enter test to run
[17:10:23.481] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[17:10:23.481] INFO: running: xray
[17:10:23.482] INFO: ----------------------------------------------------------------------
[17:10:23.482] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[17:10:23.482] INFO: ----------------------------------------------------------------------
[17:10:24.454] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[17:10:30.961] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[17:11:01.647] INFO: Resuming triggers.
[17:11:08.163] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[17:11:39.161] INFO: Resuming triggers.
[17:11:45.672] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[17:12:17.029] INFO: Resuming triggers.
[17:12:23.539] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[17:12:54.783] INFO: Resuming triggers.
[17:13:01.297] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[17:13:31.803] INFO: Resuming triggers.
[17:13:38.312] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[17:14:08.205] INFO: Resuming triggers.
[17:14:14.715] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[17:14:44.703] INFO: Resuming triggers.
[17:14:51.212] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[17:15:21.307] INFO: Resuming triggers.
[17:15:27.815] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[17:15:57.804] INFO: Resuming triggers.
[17:16:04.314] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[17:16:34.368] INFO: Resuming triggers.
[17:16:40.878] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[17:17:10.867] INFO: Resuming triggers.
[17:17:17.379] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[17:17:47.468] INFO: Resuming triggers.
[17:17:53.980] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[17:18:23.974] INFO: Resuming triggers.
[17:18:30.486] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[17:19:00.524] INFO: Resuming triggers.
[17:19:07.034] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[17:19:37.086] INFO: Resuming triggers.
[17:19:39.775] INFO: data taking finished, elapsed time: 100 seconds.
[17:19:52.435] INFO: PixTest:: pg_setup set to default.
[17:19:52.438] INFO: PixTestXray::doPhRun() done
[17:19:52.586] INFO: enter test to run
[17:20:08.773] INFO: test: HighRate no parameter change
[17:20:08.773] INFO: running: highrate
[17:20:08.774] INFO: ----------------------------------------------------------------------
[17:20:08.774] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:20:08.774] INFO: ----------------------------------------------------------------------
[17:20:08.916] INFO: Expecting 768 events.
[17:20:10.049] INFO: 768 events read in total (418ms).
[17:20:10.050] INFO: Test took 1269ms.
[17:20:10.853] INFO: Expecting 41600 events.
[17:20:13.940] INFO: 41600 events read in total (2560ms).
[17:20:13.941] INFO: Test took 3884ms.
[17:20:13.975] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:14.703] INFO: Expecting 41600 events.
[17:20:17.913] INFO: 41600 events read in total (2683ms).
[17:20:17.914] INFO: Test took 3921ms.
[17:20:17.949] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:18.672] INFO: Expecting 41600 events.
[17:20:21.874] INFO: 41600 events read in total (2675ms).
[17:20:21.875] INFO: Test took 3907ms.
[17:20:21.910] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:22.632] INFO: Expecting 41600 events.
[17:20:25.878] INFO: 41600 events read in total (2719ms).
[17:20:25.879] INFO: Test took 3950ms.
[17:20:25.914] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:26.634] INFO: Expecting 41600 events.
[17:20:29.877] INFO: 41600 events read in total (2716ms).
[17:20:29.878] INFO: Test took 3946ms.
[17:20:29.914] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:30.634] INFO: Expecting 41600 events.
[17:20:33.848] INFO: 41600 events read in total (2687ms).
[17:20:33.849] INFO: Test took 3915ms.
[17:20:33.884] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:34.605] INFO: Expecting 41600 events.
[17:20:37.871] INFO: 41600 events read in total (2740ms).
[17:20:37.872] INFO: Test took 3969ms.
[17:20:37.908] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:38.631] INFO: Expecting 41600 events.
[17:20:41.879] INFO: 41600 events read in total (2721ms).
[17:20:41.880] INFO: Test took 3955ms.
[17:20:41.915] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:42.639] INFO: Expecting 41600 events.
[17:20:45.882] INFO: 41600 events read in total (2716ms).
[17:20:45.883] INFO: Test took 3949ms.
[17:20:45.919] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:46.637] INFO: Expecting 41600 events.
[17:20:49.904] INFO: 41600 events read in total (2740ms).
[17:20:49.905] INFO: Test took 3968ms.
[17:20:49.941] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:50.662] INFO: Expecting 41600 events.
[17:20:53.911] INFO: 41600 events read in total (2722ms).
[17:20:53.912] INFO: Test took 3953ms.
[17:20:53.949] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:54.667] INFO: Expecting 41600 events.
[17:20:57.919] INFO: 41600 events read in total (2725ms).
[17:20:57.920] INFO: Test took 3952ms.
[17:20:57.956] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:58.677] INFO: Expecting 41600 events.
[17:21:01.938] INFO: 41600 events read in total (2734ms).
[17:21:01.939] INFO: Test took 3964ms.
[17:21:01.975] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:02.694] INFO: Expecting 41600 events.
[17:21:05.937] INFO: 41600 events read in total (2716ms).
[17:21:05.938] INFO: Test took 3944ms.
[17:21:05.973] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:06.695] INFO: Expecting 41600 events.
[17:21:09.955] INFO: 41600 events read in total (2733ms).
[17:21:09.956] INFO: Test took 3964ms.
[17:21:09.992] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:10.712] INFO: Expecting 41600 events.
[17:21:13.950] INFO: 41600 events read in total (2711ms).
[17:21:13.951] INFO: Test took 3940ms.
[17:21:13.986] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:14.705] INFO: Expecting 41600 events.
[17:21:17.960] INFO: 41600 events read in total (2728ms).
[17:21:17.961] INFO: Test took 3955ms.
[17:21:17.997] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:18.716] INFO: Expecting 41600 events.
[17:21:21.967] INFO: 41600 events read in total (2724ms).
[17:21:21.968] INFO: Test took 3952ms.
[17:21:22.003] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:22.722] INFO: Expecting 41600 events.
[17:21:25.942] INFO: 41600 events read in total (2693ms).
[17:21:25.942] INFO: Test took 3919ms.
[17:21:25.977] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:26.701] INFO: Expecting 41600 events.
[17:21:30.048] INFO: 41600 events read in total (2821ms).
[17:21:30.049] INFO: Test took 4053ms.
[17:21:30.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:30.447] INFO: enter test to run
[17:21:38.388] INFO: test: HighRate no parameter change
[17:21:38.388] INFO: running: highrate
[17:21:38.389] INFO: ----------------------------------------------------------------------
[17:21:38.389] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:21:38.389] INFO: ----------------------------------------------------------------------
[17:21:39.007] INFO: Expecting 208000 events.
[17:21:50.854] INFO: 208000 events read in total (11320ms).
[17:21:50.857] INFO: Test took 12461ms.
[17:21:51.011] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:51.270] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 0 3 0
[17:21:51.270] INFO: number of red-efficiency pixels: 79 211 85 124 160 169 160 146 106 126 154 121 106 72 33 30
[17:21:51.270] INFO: number of X-ray hits detected: 66297 42862 68468 114901 119370 128050 120394 89153 85521 102379 102931 85335 90391 54538 22089 26111
[17:21:51.270] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:21:51.270] INFO: number of Vcal hits detected: 207920 202996 207914 207871 207838 207824 207786 207846 207893 207874 207843 207875 207892 207928 207820 207970
[17:21:51.270] INFO: Vcal hit fiducial efficiency (%): 100.0 97.5 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[17:21:51.270] INFO: Vcal hit overall efficiency (%): 100.0 97.6 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0
[17:21:51.270] INFO: X-ray hit rate [MHz/cm2]: 19.4 12.6 20.1 33.7 35.0 37.5 35.3 26.1 25.1 30.0 30.2 25.0 26.5 16.0 6.5 7.7
[17:21:51.270] INFO: PixTestHighRate::doXPixelAlive() done
[17:21:51.315] INFO: PixTest:: pg_setup set to default.
[17:21:51.326] INFO: enter test to run
[17:22:05.876] INFO: test: HighRate no parameter change
[17:22:05.876] INFO: running: highrate
[17:22:05.877] INFO: ----------------------------------------------------------------------
[17:22:05.877] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:22:05.877] INFO: ----------------------------------------------------------------------
[17:22:06.499] INFO: Expecting 208000 events.
[17:22:20.009] INFO: 208000 events read in total (12983ms).
[17:22:20.016] INFO: Test took 14130ms.
[17:22:20.335] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:22:20.643] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 0 3 0
[17:22:20.643] INFO: number of red-efficiency pixels: 197 279 219 391 479 496 627 426 352 476 390 343 349 187 52 72
[17:22:20.643] INFO: number of X-ray hits detected: 134587 87648 139547 232666 242637 259521 243930 182111 174024 206021 210298 174430 184454 110385 45072 53056
[17:22:20.643] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:22:20.643] INFO: number of Vcal hits detected: 207792 201917 207766 207576 207486 207456 207256 207542 207628 207484 207587 207635 207627 207804 207801 207926
[17:22:20.643] INFO: Vcal hit fiducial efficiency (%): 99.9 97.0 99.9 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[17:22:20.643] INFO: Vcal hit overall efficiency (%): 99.9 97.1 99.9 99.8 99.8 99.7 99.6 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 100.0
[17:22:20.643] INFO: X-ray hit rate [MHz/cm2]: 39.4 25.7 40.9 68.2 71.1 76.1 71.5 53.4 51.0 60.4 61.6 51.1 54.1 32.4 13.2 15.6
[17:22:20.643] INFO: PixTestHighRate::doXPixelAlive() done
[17:22:20.690] INFO: PixTest:: pg_setup set to default.
[17:22:20.708] INFO: enter test to run
[17:22:33.715] INFO: test: HighRate no parameter change
[17:22:33.715] INFO: running: highrate
[17:22:33.716] INFO: ----------------------------------------------------------------------
[17:22:33.716] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:22:33.716] INFO: ----------------------------------------------------------------------
[17:22:34.335] INFO: Expecting 208000 events.
[17:22:48.775] INFO: 208000 events read in total (13913ms).
[17:22:48.783] INFO: Test took 15059ms.
[17:22:49.258] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:22:49.619] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 0 3 0
[17:22:49.619] INFO: number of red-efficiency pixels: 395 383 516 950 1109 1249 1416 949 756 1082 960 755 813 423 99 95
[17:22:49.619] INFO: number of X-ray hits detected: 204851 133194 213161 354039 368918 395880 370584 276604 265861 314089 319280 264802 280921 168001 69322 80724
[17:22:49.619] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:22:49.619] INFO: number of Vcal hits detected: 207562 201154 207364 206865 206618 206364 206056 206739 207127 206695 206813 207107 207044 207492 207749 207903
[17:22:49.619] INFO: Vcal hit fiducial efficiency (%): 99.8 96.6 99.7 99.5 99.4 99.3 99.2 99.4 99.6 99.4 99.5 99.6 99.6 99.8 100.0 100.0
[17:22:49.619] INFO: Vcal hit overall efficiency (%): 99.8 96.7 99.7 99.5 99.3 99.2 99.1 99.4 99.6 99.4 99.4 99.6 99.5 99.8 99.9 100.0
[17:22:49.620] INFO: X-ray hit rate [MHz/cm2]: 60.0 39.0 62.5 103.8 108.1 116.0 108.6 81.1 77.9 92.1 93.6 77.6 82.3 49.2 20.3 23.7
[17:22:49.620] INFO: PixTestHighRate::doXPixelAlive() done
[17:22:49.665] INFO: PixTest:: pg_setup set to default.
[17:22:49.681] INFO: enter test to run
[17:22:55.540] INFO: test: exit no parameter change
[17:22:55.924] QUIET: Connection to board 33 closed.
[17:22:55.925] INFO: pXar: this is the end, my friend