[13:15:40.406] INFO: *** Welcome to pxar ***
[13:15:40.406] INFO: *** Today: 2016/06/16
[13:15:40.424] INFO: *** Version: v1.9.0-814-g7497
[13:15:40.424] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//dacParameters35_C15.dat
[13:15:40.455] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:15:40.455] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//defaultMaskFile.dat
[13:15:40.455] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C15.dat
[13:15:40.551] INFO: clk: 4
[13:15:40.551] INFO: ctr: 4
[13:15:40.551] INFO: sda: 19
[13:15:40.551] INFO: tin: 9
[13:15:40.551] INFO: level: 15
[13:15:40.551] INFO: triggerdelay: 0
[13:15:40.551] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[13:15:40.551] INFO: Log level: INFO
[13:15:40.569] QUIET: Connection to board DTB_WREKRL opened.
[13:15:40.572] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[13:15:40.575] INFO: RPC call hashes of host and DTB match: 398089610
[13:15:42.107] INFO: DUT info:
[13:15:42.108] INFO: The DUT currently contains the following objects:
[13:15:42.108] INFO: 2 TBM Cores tbm08c (2 ON)
[13:15:42.108] INFO: TBM Core alpha (0): 7 registers set
[13:15:42.108] INFO: TBM Core beta (1): 7 registers set
[13:15:42.108] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:15:42.108] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.108] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.108] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.108] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.108] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.108] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.108] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.108] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.108] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.108] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.108] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.108] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.108] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.108] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.108] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.108] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:15:42.526] INFO: enter 'restricted' command line mode
[13:15:42.526] INFO: enter test to run
[13:16:06.340] INFO: test: PixelAlive no parameter change
[13:16:06.340] INFO: running: pixelalive
[13:16:06.349] INFO: ----------------------------------------------------------------------
[13:16:06.349] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:16:06.349] INFO: ----------------------------------------------------------------------
[13:16:06.667] INFO: Expecting 41600 events.
[13:16:10.982] INFO: 41600 events read in total (3596ms).
[13:16:11.149] INFO: Test took 4797ms.
[13:16:11.162] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:11.425] INFO: PixTestAlive::aliveTest() done
[13:16:11.425] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:16:11.457] INFO: enter test to run
[13:16:36.044] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:16:36.044] INFO: running: highrate
[13:16:36.044] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:16:36.251] INFO: ----------------------------------------------------------------------
[13:16:36.251] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:16:36.251] INFO: ----------------------------------------------------------------------
[13:16:36.251] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:16:36.251] INFO: edge/corner pixel THR is adjusted
[13:16:36.251] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:16:37.211] INFO: Collecting data for 5 seconds...
[13:16:42.228] INFO: Done with hot pixel readout
[13:16:53.712] INFO: PixTest:: pg_setup set to default.
[13:16:53.713] INFO: 3 hot pixels found in step 0
[13:16:54.700] INFO: Collecting data for 5 seconds...
[13:16:59.716] INFO: Done with hot pixel readout
[13:17:11.128] INFO: PixTest:: pg_setup set to default.
[13:17:11.129] INFO: 1 hot pixels found in step 1
[13:17:12.121] INFO: Collecting data for 5 seconds...
[13:17:17.137] INFO: Done with hot pixel readout
[13:17:28.551] INFO: PixTest:: pg_setup set to default.
[13:17:28.552] INFO: 2 hot pixels found in step 2
[13:17:29.540] INFO: Collecting data for 5 seconds...
[13:17:34.555] INFO: Done with hot pixel readout
[13:17:46.002] INFO: PixTest:: pg_setup set to default.
[13:17:46.003] INFO: 0 hot pixels found in step 3
[13:17:46.035] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:17:46.039] INFO: PixTest::trimHotPixels() done
[13:17:46.039] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C0.dat
[13:17:46.044] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C1.dat
[13:17:46.050] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C2.dat
[13:17:46.056] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C3.dat
[13:17:46.062] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C4.dat
[13:17:46.067] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C5.dat
[13:17:46.072] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C6.dat
[13:17:46.077] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C7.dat
[13:17:46.082] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C8.dat
[13:17:46.088] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C9.dat
[13:17:46.093] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C10.dat
[13:17:46.098] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C11.dat
[13:17:46.103] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C12.dat
[13:17:46.108] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C13.dat
[13:17:46.114] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C14.dat
[13:17:46.119] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C15.dat
[13:17:46.124] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//defaultMaskFile.dat
[13:17:46.134] INFO: enter test to run
[13:18:28.251] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:18:28.251] INFO: running: highrate
[13:18:28.256] INFO: ----------------------------------------------------------------------
[13:18:28.256] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:18:28.256] INFO: ----------------------------------------------------------------------
[13:18:28.256] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:18:28.256] INFO: edge/corner pixel THR is adjusted
[13:18:28.256] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:18:29.214] INFO: Collecting data for 1 seconds...
[13:18:30.218] INFO: Done with hot pixel readout
[13:18:34.065] INFO: PixTest:: pg_setup set to default.
[13:18:34.066] INFO: 0 hot pixels found in step 0
[13:18:34.071] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:18:34.170] INFO: PixTest::trimHotPixels() done
[13:18:34.170] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C0.dat
[13:18:34.177] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C1.dat
[13:18:34.183] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C2.dat
[13:18:34.188] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C3.dat
[13:18:34.193] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C4.dat
[13:18:34.198] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C5.dat
[13:18:34.204] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C6.dat
[13:18:34.209] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C7.dat
[13:18:34.214] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C8.dat
[13:18:34.219] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C9.dat
[13:18:34.228] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C10.dat
[13:18:34.239] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C11.dat
[13:18:34.244] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C12.dat
[13:18:34.250] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C13.dat
[13:18:34.255] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C14.dat
[13:18:34.260] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C15.dat
[13:18:34.266] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-1-14_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//defaultMaskFile.dat
[13:18:34.275] INFO: enter test to run
[13:19:11.747] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:19:11.747] INFO: running: xray
[13:19:11.749] INFO: ----------------------------------------------------------------------
[13:19:11.749] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:19:11.749] INFO: ----------------------------------------------------------------------
[13:19:12.713] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:19:24.440] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:19:53.783] INFO: Resuming triggers.
[13:20:05.516] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:20:34.906] INFO: Resuming triggers.
[13:20:46.636] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[13:21:16.056] INFO: Resuming triggers.
[13:21:27.792] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[13:21:57.186] INFO: Resuming triggers.
[13:22:08.921] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[13:22:38.372] INFO: Resuming triggers.
[13:22:50.110] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[13:23:19.426] INFO: Resuming triggers.
[13:23:31.165] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[13:24:00.529] INFO: Resuming triggers.
[13:24:12.273] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[13:24:41.842] INFO: Resuming triggers.
[13:24:48.271] INFO: data taking finished, elapsed time: 100 seconds.
[13:25:04.625] INFO: PixTest:: pg_setup set to default.
[13:25:04.629] INFO: PixTestXray::doPhRun() done
[13:25:04.787] INFO: enter test to run
[13:26:07.496] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:26:07.496] INFO: running: xray
[13:26:07.497] INFO: ----------------------------------------------------------------------
[13:26:07.497] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:26:07.497] INFO: ----------------------------------------------------------------------
[13:26:08.465] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:26:15.531] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[13:26:45.748] INFO: Resuming triggers.
[13:26:52.813] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[13:27:23.077] INFO: Resuming triggers.
[13:27:30.140] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[13:28:00.350] INFO: Resuming triggers.
[13:28:07.416] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[13:28:37.685] INFO: Resuming triggers.
[13:28:44.757] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[13:29:14.966] INFO: Resuming triggers.
[13:29:22.038] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[13:29:52.299] INFO: Resuming triggers.
[13:29:59.368] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[13:30:29.719] INFO: Resuming triggers.
[13:30:36.789] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[13:31:07.049] INFO: Resuming triggers.
[13:31:14.125] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[13:31:44.435] INFO: Resuming triggers.
[13:31:51.511] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[13:32:21.792] INFO: Resuming triggers.
[13:32:28.867] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[13:32:59.195] INFO: Resuming triggers.
[13:33:06.274] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[13:33:36.607] INFO: Resuming triggers.
[13:33:43.686] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[13:34:13.873] INFO: Resuming triggers.
[13:34:20.952] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[13:34:51.114] INFO: Resuming triggers.
[13:34:52.418] INFO: data taking finished, elapsed time: 100 seconds.
[13:34:58.334] INFO: PixTest:: pg_setup set to default.
[13:34:58.337] INFO: PixTestXray::doPhRun() done
[13:34:58.487] INFO: enter test to run
[13:35:26.748] INFO: test: HighRate no parameter change
[13:35:26.748] INFO: running: highrate
[13:35:26.750] INFO: ----------------------------------------------------------------------
[13:35:26.750] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:35:26.750] INFO: ----------------------------------------------------------------------
[13:35:26.892] INFO: Expecting 768 events.
[13:35:28.026] INFO: 768 events read in total (419ms).
[13:35:28.026] INFO: Test took 1269ms.
[13:35:28.829] INFO: Expecting 41600 events.
[13:35:31.915] INFO: 41600 events read in total (2559ms).
[13:35:31.916] INFO: Test took 3882ms.
[13:35:31.947] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:32.678] INFO: Expecting 41600 events.
[13:35:35.867] INFO: 41600 events read in total (2662ms).
[13:35:35.868] INFO: Test took 3905ms.
[13:35:35.899] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:36.629] INFO: Expecting 41600 events.
[13:35:39.835] INFO: 41600 events read in total (2676ms).
[13:35:39.836] INFO: Test took 3919ms.
[13:35:39.867] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:40.593] INFO: Expecting 41600 events.
[13:35:43.813] INFO: 41600 events read in total (2693ms).
[13:35:43.814] INFO: Test took 3931ms.
[13:35:43.845] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:44.575] INFO: Expecting 41600 events.
[13:35:47.784] INFO: 41600 events read in total (2682ms).
[13:35:47.785] INFO: Test took 3922ms.
[13:35:47.817] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:48.546] INFO: Expecting 41600 events.
[13:35:51.742] INFO: 41600 events read in total (2669ms).
[13:35:51.743] INFO: Test took 3909ms.
[13:35:51.774] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:52.503] INFO: Expecting 41600 events.
[13:35:55.713] INFO: 41600 events read in total (2683ms).
[13:35:55.713] INFO: Test took 3921ms.
[13:35:55.745] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:56.472] INFO: Expecting 41600 events.
[13:35:59.689] INFO: 41600 events read in total (2690ms).
[13:35:59.690] INFO: Test took 3927ms.
[13:35:59.721] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:00.453] INFO: Expecting 41600 events.
[13:36:03.668] INFO: 41600 events read in total (2689ms).
[13:36:03.669] INFO: Test took 3930ms.
[13:36:03.701] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:04.428] INFO: Expecting 41600 events.
[13:36:07.651] INFO: 41600 events read in total (2696ms).
[13:36:07.652] INFO: Test took 3934ms.
[13:36:07.684] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:08.409] INFO: Expecting 41600 events.
[13:36:11.625] INFO: 41600 events read in total (2689ms).
[13:36:11.626] INFO: Test took 3926ms.
[13:36:11.657] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:12.385] INFO: Expecting 41600 events.
[13:36:15.588] INFO: 41600 events read in total (2676ms).
[13:36:15.589] INFO: Test took 3914ms.
[13:36:15.621] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:16.351] INFO: Expecting 41600 events.
[13:36:19.556] INFO: 41600 events read in total (2678ms).
[13:36:19.557] INFO: Test took 3919ms.
[13:36:19.589] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:20.315] INFO: Expecting 41600 events.
[13:36:23.529] INFO: 41600 events read in total (2687ms).
[13:36:23.530] INFO: Test took 3923ms.
[13:36:23.562] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:24.289] INFO: Expecting 41600 events.
[13:36:27.491] INFO: 41600 events read in total (2675ms).
[13:36:27.492] INFO: Test took 3912ms.
[13:36:27.531] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:28.253] INFO: Expecting 41600 events.
[13:36:31.459] INFO: 41600 events read in total (2680ms).
[13:36:31.460] INFO: Test took 3912ms.
[13:36:31.491] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:32.218] INFO: Expecting 41600 events.
[13:36:35.433] INFO: 41600 events read in total (2689ms).
[13:36:35.434] INFO: Test took 3926ms.
[13:36:35.466] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:36.193] INFO: Expecting 41600 events.
[13:36:39.397] INFO: 41600 events read in total (2677ms).
[13:36:39.398] INFO: Test took 3915ms.
[13:36:39.429] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:40.160] INFO: Expecting 41600 events.
[13:36:43.340] INFO: 41600 events read in total (2653ms).
[13:36:43.341] INFO: Test took 3896ms.
[13:36:43.372] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:44.098] INFO: Expecting 41600 events.
[13:36:47.146] INFO: 41600 events read in total (2521ms).
[13:36:47.147] INFO: Test took 3757ms.
[13:36:47.178] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:47.543] INFO: enter test to run
[13:37:05.420] INFO: test: HighRate no parameter change
[13:37:05.420] INFO: running: highrate
[13:37:05.421] INFO: ----------------------------------------------------------------------
[13:37:05.421] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:37:05.421] INFO: ----------------------------------------------------------------------
[13:37:06.040] INFO: Expecting 208000 events.
[13:37:17.822] INFO: 208000 events read in total (11255ms).
[13:37:17.824] INFO: Test took 12395ms.
[13:37:17.956] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:18.205] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:37:18.205] INFO: number of red-efficiency pixels: 58 39 68 110 115 155 147 106 95 106 122 76 85 44 24 21
[13:37:18.206] INFO: number of X-ray hits detected: 59810 37594 60421 98852 101427 104707 110115 75327 74919 88443 91283 73035 76961 43252 17395 20423
[13:37:18.206] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:37:18.206] INFO: number of Vcal hits detected: 207941 207959 207932 207886 207884 207842 207853 207890 207903 207892 207874 207923 207914 207956 207976 207979
[13:37:18.206] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[13:37:18.206] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[13:37:18.206] INFO: X-ray hit rate [MHz/cm2]: 17.5 11.0 17.7 29.0 29.7 30.7 32.3 22.1 22.0 25.9 26.8 21.4 22.6 12.7 5.1 6.0
[13:37:18.206] INFO: PixTestHighRate::doXPixelAlive() done
[13:37:18.251] INFO: PixTest:: pg_setup set to default.
[13:37:18.264] INFO: enter test to run
[13:37:45.723] INFO: test: HighRate no parameter change
[13:37:45.724] INFO: running: highrate
[13:37:45.725] INFO: ----------------------------------------------------------------------
[13:37:45.725] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:37:45.725] INFO: ----------------------------------------------------------------------
[13:37:46.339] INFO: Expecting 208000 events.
[13:37:59.664] INFO: 208000 events read in total (12798ms).
[13:37:59.670] INFO: Test took 13937ms.
[13:37:59.943] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:00.235] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:38:00.235] INFO: number of red-efficiency pixels: 162 103 191 417 334 480 512 283 260 319 268 252 262 140 31 61
[13:38:00.235] INFO: number of X-ray hits detected: 123791 78119 124583 202670 211109 216094 226233 156205 155533 183363 189365 151107 160151 89237 36025 42531
[13:38:00.235] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:38:00.235] INFO: number of Vcal hits detected: 207826 207894 207795 207553 207643 207482 207438 207702 207732 207660 207717 207742 207724 207854 207969 207938
[13:38:00.235] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.7 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[13:38:00.235] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[13:38:00.235] INFO: X-ray hit rate [MHz/cm2]: 36.3 22.9 36.5 59.4 61.9 63.3 66.3 45.8 45.6 53.7 55.5 44.3 46.9 26.2 10.6 12.5
[13:38:00.235] INFO: PixTestHighRate::doXPixelAlive() done
[13:38:00.284] INFO: PixTest:: pg_setup set to default.
[13:38:00.296] INFO: enter test to run
[13:38:30.179] INFO: test: HighRate no parameter change
[13:38:30.179] INFO: running: highrate
[13:38:30.180] INFO: ----------------------------------------------------------------------
[13:38:30.180] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:38:30.180] INFO: ----------------------------------------------------------------------
[13:38:30.796] INFO: Expecting 208000 events.
[13:38:45.815] INFO: 208000 events read in total (14492ms).
[13:38:45.822] INFO: Test took 15633ms.
[13:38:46.228] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:46.564] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:38:46.564] INFO: number of red-efficiency pixels: 313 212 387 934 758 1079 1198 632 576 821 604 552 578 287 67 70
[13:38:46.564] INFO: number of X-ray hits detected: 186264 117351 187842 305897 318423 325492 341712 235488 234152 276390 285708 228135 240347 134049 54450 64023
[13:38:46.564] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:38:46.564] INFO: number of Vcal hits detected: 207652 207772 207546 206866 207134 206651 206465 207220 207358 207063 207299 207376 207365 207684 207931 207930
[13:38:46.564] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.5 99.6 99.4 99.3 99.7 99.7 99.6 99.7 99.7 99.7 99.9 100.0 100.0
[13:38:46.564] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.5 99.6 99.4 99.3 99.6 99.7 99.5 99.7 99.7 99.7 99.8 100.0 100.0
[13:38:46.564] INFO: X-ray hit rate [MHz/cm2]: 54.6 34.4 55.1 89.7 93.3 95.4 100.2 69.0 68.6 81.0 83.7 66.9 70.4 39.3 16.0 18.8
[13:38:46.564] INFO: PixTestHighRate::doXPixelAlive() done
[13:38:46.610] INFO: PixTest:: pg_setup set to default.
[13:38:46.632] INFO: enter test to run
[13:39:12.147] INFO: test: exit no parameter change
[13:39:12.456] QUIET: Connection to board 33 closed.
[13:39:12.458] INFO: pXar: this is the end, my friend