[10:28:31.470] INFO: *** Welcome to pxar ***
[10:28:31.471] INFO: *** Today: 2016/05/05
[10:28:31.488] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:28:31.488] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//dacParameters35_C15.dat
[10:28:31.941] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:28:31.941] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//defaultMaskFile.dat
[10:28:31.968] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C15.dat
[10:28:33.407] INFO: clk: 4
[10:28:33.407] INFO: ctr: 4
[10:28:33.407] INFO: sda: 19
[10:28:33.407] INFO: tin: 9
[10:28:33.407] INFO: level: 15
[10:28:33.407] INFO: triggerdelay: 0
[10:28:33.407] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:28:33.407] INFO: Log level: INFO
[10:28:33.423] QUIET: Connection to board DTB_WREK4U opened.
[10:28:33.427] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[10:28:33.429] INFO: RPC call hashes of host and DTB match: 398089610
[10:28:34.964] INFO: DUT info:
[10:28:34.964] INFO: The DUT currently contains the following objects:
[10:28:34.964] INFO: 2 TBM Cores tbm08c (2 ON)
[10:28:34.964] INFO: TBM Core alpha (0): 7 registers set
[10:28:34.965] INFO: TBM Core beta (1): 7 registers set
[10:28:34.965] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:28:34.965] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:34.965] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:34.965] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:34.965] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:34.965] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:34.965] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:34.965] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:34.965] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:34.965] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:34.965] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:34.965] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:34.965] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:34.965] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:34.965] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:34.965] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:34.965] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:35.401] INFO: enter 'restricted' command line mode
[10:28:35.401] INFO: enter test to run
[10:28:50.372] INFO: test: PixelAlive no parameter change
[10:28:50.372] INFO: running: pixelalive
[10:28:50.381] INFO: ----------------------------------------------------------------------
[10:28:50.381] 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:28:50.381] INFO: ----------------------------------------------------------------------
[10:28:50.706] INFO: Expecting 41600 events.
[10:28:54.861] INFO: 41600 events read in total (3437ms).
[10:28:55.023] INFO: Test took 4639ms.
[10:28:55.034] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:55.296] INFO: PixTestAlive::aliveTest() done
[10:28:55.296] INFO: number of dead pixels (per ROC): 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0
[10:28:55.324] INFO: enter test to run
[10:29:25.692] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:29:25.692] INFO: running: highrate
[10:29:25.692] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:29:27.712] INFO: ----------------------------------------------------------------------
[10:29:27.712] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:29:27.712] INFO: ----------------------------------------------------------------------
[10:29:27.712] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:29:27.713] INFO: edge/corner pixel THR is adjusted
[10:29:27.713] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:29:28.670] INFO: Collecting data for 5 seconds...
[10:29:33.687] INFO: Done with hot pixel readout
[10:29:43.535] INFO: PixTest:: pg_setup set to default.
[10:29:43.536] INFO: 7 hot pixels found in step 0
[10:29:44.526] INFO: Collecting data for 5 seconds...
[10:29:49.543] INFO: Done with hot pixel readout
[10:30:00.110] INFO: PixTest:: pg_setup set to default.
[10:30:00.111] INFO: 5 hot pixels found in step 1
[10:30:01.108] INFO: Collecting data for 5 seconds...
[10:30:06.125] INFO: Done with hot pixel readout
[10:30:17.309] INFO: PixTest:: pg_setup set to default.
[10:30:17.310] INFO: 5 hot pixels found in step 2
[10:30:18.301] INFO: Collecting data for 5 seconds...
[10:30:23.318] INFO: Done with hot pixel readout
[10:30:34.602] INFO: PixTest:: pg_setup set to default.
[10:30:34.603] INFO: 4 hot pixels found in step 3
[10:30:35.594] INFO: Collecting data for 5 seconds...
[10:30:40.611] INFO: Done with hot pixel readout
[10:30:51.706] INFO: PixTest:: pg_setup set to default.
[10:30:51.707] INFO: 2 hot pixels found in step 4
[10:30:52.698] INFO: Collecting data for 5 seconds...
[10:30:57.715] INFO: Done with hot pixel readout
[10:31:08.893] INFO: PixTest:: pg_setup set to default.
[10:31:08.894] INFO: 2 hot pixels found in step 5
[10:31:09.885] INFO: Collecting data for 5 seconds...
[10:31:14.901] INFO: Done with hot pixel readout
[10:31:26.098] INFO: PixTest:: pg_setup set to default.
[10:31:26.099] INFO: 0 hot pixels found in step 6
[10:31:26.134] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:31:26.137] INFO: PixTest::trimHotPixels() done
[10:31:26.138] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C0.dat
[10:31:26.144] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C1.dat
[10:31:26.150] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C2.dat
[10:31:26.156] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C3.dat
[10:31:26.161] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C4.dat
[10:31:26.166] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C5.dat
[10:31:26.172] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C6.dat
[10:31:26.177] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C7.dat
[10:31:26.182] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C8.dat
[10:31:26.188] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C9.dat
[10:31:26.193] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C10.dat
[10:31:26.198] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C11.dat
[10:31:26.204] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C12.dat
[10:31:26.209] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C13.dat
[10:31:26.214] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C14.dat
[10:31:26.220] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C15.dat
[10:31:26.225] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//defaultMaskFile.dat
[10:31:26.236] INFO: enter test to run
[10:31:51.875] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:31:51.875] INFO: running: highrate
[10:31:51.879] INFO: ----------------------------------------------------------------------
[10:31:51.879] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:31:51.879] INFO: ----------------------------------------------------------------------
[10:31:51.879] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:31:51.879] INFO: edge/corner pixel THR is adjusted
[10:31:51.879] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:31:52.836] INFO: Collecting data for 1 seconds...
[10:31:53.841] INFO: Done with hot pixel readout
[10:31:57.991] INFO: PixTest:: pg_setup set to default.
[10:31:57.992] INFO: 0 hot pixels found in step 0
[10:31:57.997] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:31:58.077] INFO: PixTest::trimHotPixels() done
[10:31:58.077] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C0.dat
[10:31:58.083] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C1.dat
[10:31:58.089] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C2.dat
[10:31:58.094] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C3.dat
[10:31:58.100] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C4.dat
[10:31:58.105] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C5.dat
[10:31:58.110] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C6.dat
[10:31:58.116] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C7.dat
[10:31:58.121] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C8.dat
[10:31:58.127] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C9.dat
[10:31:58.132] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C10.dat
[10:31:58.138] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C11.dat
[10:31:58.143] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C12.dat
[10:31:58.148] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C13.dat
[10:31:58.154] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C14.dat
[10:31:58.159] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//trimParameters35_C15.dat
[10:31:58.165] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-1-50_FPIXTest-17C-FNAL-160429-1423_2016-04-29_14h23m_1461957819/000_FPIXTest_p17//defaultMaskFile.dat
[10:31:58.174] INFO: enter test to run
[10:32:15.035] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:32:15.035] INFO: running: xray
[10:32:15.036] INFO: ----------------------------------------------------------------------
[10:32:15.036] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:32:15.036] INFO: ----------------------------------------------------------------------
[10:32:15.999] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:32:27.291] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:32:56.079] INFO: Resuming triggers.
[10:33:07.374] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[10:33:36.712] INFO: Resuming triggers.
[10:33:48.003] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:34:17.457] INFO: Resuming triggers.
[10:34:28.749] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:34:58.153] INFO: Resuming triggers.
[10:35:09.441] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[10:35:38.841] INFO: Resuming triggers.
[10:35:50.130] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[10:36:19.552] INFO: Resuming triggers.
[10:36:30.849] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[10:37:00.153] INFO: Resuming triggers.
[10:37:11.448] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[10:37:40.885] INFO: Resuming triggers.
[10:37:50.875] INFO: data taking finished, elapsed time: 100 seconds.
[10:38:16.999] INFO: PixTest:: pg_setup set to default.
[10:38:17.002] INFO: PixTestXray::doPhRun() done
[10:38:17.181] INFO: enter test to run
[10:38:51.168] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:38:51.168] INFO: running: xray
[10:38:51.169] INFO: ----------------------------------------------------------------------
[10:38:51.169] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:38:51.169] INFO: ----------------------------------------------------------------------
[10:38:52.132] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:38:58.596] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:39:29.576] INFO: Resuming triggers.
[10:39:36.039] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[10:40:07.283] INFO: Resuming triggers.
[10:40:13.748] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[10:40:44.896] INFO: Resuming triggers.
[10:40:51.358] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[10:41:21.428] INFO: Resuming triggers.
[10:41:27.893] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[10:41:58.006] INFO: Resuming triggers.
[10:42:04.469] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[10:42:34.522] INFO: Resuming triggers.
[10:42:40.984] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:43:11.736] INFO: Resuming triggers.
[10:43:18.196] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[10:43:48.346] INFO: Resuming triggers.
[10:43:54.809] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[10:44:24.854] INFO: Resuming triggers.
[10:44:31.320] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[10:45:01.318] INFO: Resuming triggers.
[10:45:07.777] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[10:45:37.824] INFO: Resuming triggers.
[10:45:44.284] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[10:46:14.351] INFO: Resuming triggers.
[10:46:20.812] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[10:46:50.831] INFO: Resuming triggers.
[10:46:57.292] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[10:47:27.169] INFO: Resuming triggers.
[10:47:33.635] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[10:48:03.666] INFO: Resuming triggers.
[10:48:07.053] INFO: data taking finished, elapsed time: 100 seconds.
[10:48:23.019] INFO: PixTest:: pg_setup set to default.
[10:48:23.022] INFO: PixTestXray::doPhRun() done
[10:48:23.170] INFO: enter test to run
[10:48:46.179] INFO: test: HighRate no parameter change
[10:48:46.179] INFO: running: highrate
[10:48:46.180] INFO: ----------------------------------------------------------------------
[10:48:46.180] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:48:46.180] INFO: ----------------------------------------------------------------------
[10:48:46.319] INFO: Expecting 768 events.
[10:48:47.453] INFO: 768 events read in total (418ms).
[10:48:47.454] INFO: Test took 1269ms.
[10:48:48.257] INFO: Expecting 41600 events.
[10:48:51.404] INFO: 41600 events read in total (2621ms).
[10:48:51.405] INFO: Test took 3944ms.
[10:48:51.439] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:52.162] INFO: Expecting 41600 events.
[10:48:55.379] INFO: 41600 events read in total (2691ms).
[10:48:55.380] INFO: Test took 3924ms.
[10:48:55.415] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:56.137] INFO: Expecting 41600 events.
[10:48:59.342] INFO: 41600 events read in total (2678ms).
[10:48:59.343] INFO: Test took 3909ms.
[10:48:59.378] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:00.104] INFO: Expecting 41600 events.
[10:49:03.346] INFO: 41600 events read in total (2715ms).
[10:49:03.347] INFO: Test took 3950ms.
[10:49:03.381] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:04.102] INFO: Expecting 41600 events.
[10:49:07.306] INFO: 41600 events read in total (2677ms).
[10:49:07.307] INFO: Test took 3909ms.
[10:49:07.342] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:08.060] INFO: Expecting 41600 events.
[10:49:11.292] INFO: 41600 events read in total (2705ms).
[10:49:11.293] INFO: Test took 3932ms.
[10:49:11.328] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:12.049] INFO: Expecting 41600 events.
[10:49:15.311] INFO: 41600 events read in total (2735ms).
[10:49:15.312] INFO: Test took 3965ms.
[10:49:15.347] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:16.066] INFO: Expecting 41600 events.
[10:49:19.262] INFO: 41600 events read in total (2669ms).
[10:49:19.263] INFO: Test took 3898ms.
[10:49:19.300] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:20.020] INFO: Expecting 41600 events.
[10:49:23.280] INFO: 41600 events read in total (2733ms).
[10:49:23.281] INFO: Test took 3962ms.
[10:49:23.316] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:24.038] INFO: Expecting 41600 events.
[10:49:27.265] INFO: 41600 events read in total (2700ms).
[10:49:27.266] INFO: Test took 3931ms.
[10:49:27.301] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:28.023] INFO: Expecting 41600 events.
[10:49:31.210] INFO: 41600 events read in total (2660ms).
[10:49:31.211] INFO: Test took 3891ms.
[10:49:31.246] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:31.965] INFO: Expecting 41600 events.
[10:49:35.220] INFO: 41600 events read in total (2728ms).
[10:49:35.221] INFO: Test took 3956ms.
[10:49:35.257] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:35.979] INFO: Expecting 41600 events.
[10:49:38.943] INFO: 41600 events read in total (2438ms).
[10:49:38.944] INFO: Test took 3669ms.
[10:49:38.978] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:39.704] INFO: Expecting 41600 events.
[10:49:42.946] INFO: 41600 events read in total (2716ms).
[10:49:42.947] INFO: Test took 3951ms.
[10:49:42.982] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:43.700] INFO: Expecting 41600 events.
[10:49:46.933] INFO: 41600 events read in total (2706ms).
[10:49:46.934] INFO: Test took 3934ms.
[10:49:46.969] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:47.692] INFO: Expecting 41600 events.
[10:49:50.676] INFO: 41600 events read in total (2458ms).
[10:49:50.677] INFO: Test took 3690ms.
[10:49:50.713] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:51.433] INFO: Expecting 41600 events.
[10:49:54.696] INFO: 41600 events read in total (2736ms).
[10:49:54.697] INFO: Test took 3965ms.
[10:49:54.731] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:55.454] INFO: Expecting 41600 events.
[10:49:58.571] INFO: 41600 events read in total (2590ms).
[10:49:58.572] INFO: Test took 3822ms.
[10:49:58.608] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:59.332] INFO: Expecting 41600 events.
[10:50:02.546] INFO: 41600 events read in total (2687ms).
[10:50:02.547] INFO: Test took 3919ms.
[10:50:02.582] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:03.306] INFO: Expecting 41600 events.
[10:50:06.368] INFO: 41600 events read in total (2536ms).
[10:50:06.369] INFO: Test took 3769ms.
[10:50:06.403] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:06.766] INFO: enter test to run
[10:50:09.410] INFO: test: HighRate no parameter change
[10:50:09.410] INFO: running: highrate
[10:50:09.411] INFO: ----------------------------------------------------------------------
[10:50:09.412] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:50:09.412] INFO: ----------------------------------------------------------------------
[10:50:10.028] INFO: Expecting 208000 events.
[10:50:21.274] INFO: 208000 events read in total (10719ms).
[10:50:21.278] INFO: Test took 11860ms.
[10:50:21.428] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:21.684] INFO: number of dead pixels (per ROC): 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0
[10:50:21.684] INFO: number of red-efficiency pixels: 75 51 84 127 149 172 154 121 90 120 121 124 102 61 22 33
[10:50:21.684] INFO: number of X-ray hits detected: 61119 42034 71400 111020 114766 119572 119686 83353 86711 100193 92535 84244 86561 51486 22320 25747
[10:50:21.684] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:50:21.684] INFO: number of Vcal hits detected: 207925 207948 207914 207871 207746 207821 207841 207876 207906 207876 207877 207875 207897 207938 207978 207967
[10:50:21.684] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[10:50:21.684] 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 99.9 100.0 100.0 100.0 100.0
[10:50:21.684] INFO: X-ray hit rate [MHz/cm2]: 17.9 12.3 20.9 32.5 33.6 35.0 35.1 24.4 25.4 29.4 27.1 24.7 25.4 15.1 6.5 7.5
[10:50:21.684] INFO: PixTestHighRate::doXPixelAlive() done
[10:50:21.730] INFO: PixTest:: pg_setup set to default.
[10:50:21.739] INFO: enter test to run
[10:50:32.426] INFO: test: HighRate no parameter change
[10:50:32.427] INFO: running: highrate
[10:50:32.428] INFO: ----------------------------------------------------------------------
[10:50:32.428] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:50:32.428] INFO: ----------------------------------------------------------------------
[10:50:33.047] INFO: Expecting 208000 events.
[10:50:46.592] INFO: 208000 events read in total (13018ms).
[10:50:46.598] INFO: Test took 14162ms.
[10:50:46.911] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:47.226] INFO: number of dead pixels (per ROC): 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0
[10:50:47.226] INFO: number of red-efficiency pixels: 170 135 324 390 545 620 669 330 342 386 374 344 337 171 48 46
[10:50:47.226] INFO: number of X-ray hits detected: 129285 90406 151088 235242 243872 255385 253891 178325 183468 215634 198032 179732 183151 109210 48355 55409
[10:50:47.226] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:50:47.226] INFO: number of Vcal hits detected: 207823 207857 207628 207578 207300 207294 207260 207649 207631 207586 207605 207627 207639 207818 207951 207954
[10:50:47.226] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[10:50:47.226] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.6 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[10:50:47.226] INFO: X-ray hit rate [MHz/cm2]: 37.9 26.5 44.3 69.0 71.5 74.9 74.4 52.3 53.8 63.2 58.0 52.7 53.7 32.0 14.2 16.2
[10:50:47.226] INFO: PixTestHighRate::doXPixelAlive() done
[10:50:47.273] INFO: PixTest:: pg_setup set to default.
[10:50:47.293] INFO: enter test to run
[10:50:58.266] INFO: test: HighRate no parameter change
[10:50:58.266] INFO: running: highrate
[10:50:58.268] INFO: ----------------------------------------------------------------------
[10:50:58.268] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:50:58.268] INFO: ----------------------------------------------------------------------
[10:50:58.899] INFO: Expecting 208000 events.
[10:51:15.034] INFO: 208000 events read in total (15609ms).
[10:51:15.042] INFO: Test took 16764ms.
[10:51:15.524] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:15.891] INFO: number of dead pixels (per ROC): 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0
[10:51:15.891] INFO: number of red-efficiency pixels: 414 304 776 941 1330 1577 1580 757 744 941 923 811 809 437 102 85
[10:51:15.891] INFO: number of X-ray hits detected: 198407 138481 231352 359637 373186 387320 386782 272613 281673 328531 301081 275439 280965 167169 73332 84139
[10:51:15.891] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:51:15.891] INFO: number of Vcal hits detected: 207530 207665 206866 206861 206149 205802 205781 207051 207102 206862 206887 207044 207058 207486 207894 207909
[10:51:15.891] INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.5 99.5 99.2 99.0 99.0 99.6 99.6 99.5 99.5 99.6 99.6 99.8 100.0 100.0
[10:51:15.891] INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.5 99.5 99.1 98.9 98.9 99.5 99.6 99.5 99.5 99.5 99.5 99.8 99.9 100.0
[10:51:15.891] INFO: X-ray hit rate [MHz/cm2]: 58.2 40.6 67.8 105.4 109.4 113.5 113.4 79.9 82.6 96.3 88.2 80.7 82.4 49.0 21.5 24.7
[10:51:15.891] INFO: PixTestHighRate::doXPixelAlive() done
[10:51:15.936] INFO: PixTest:: pg_setup set to default.
[10:51:15.955] INFO: enter test to run
[10:51:17.562] INFO: test: exit no parameter change
[10:51:17.967] QUIET: Connection to board 32 closed.
[10:51:17.968] INFO: pXar: this is the end, my friend