[14:13:59.879] INFO: *** Welcome to pxar ***
[14:13:59.879] INFO: *** Today: 2016/09/07
[14:14:01.062] INFO: *** Version: v1.9.0-818-g96727
[14:14:01.062] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//dacParameters35_C15.dat
[14:14:01.130] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:14:01.130] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//defaultMaskFile.dat
[14:14:01.130] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C15.dat
[14:14:01.233] INFO: clk: 4
[14:14:01.233] INFO: ctr: 4
[14:14:01.233] INFO: sda: 19
[14:14:01.233] INFO: tin: 9
[14:14:01.233] INFO: level: 15
[14:14:01.233] INFO: triggerdelay: 0
[14:14:01.233] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[14:14:01.233] INFO: Log level: INFO
[14:14:01.249] QUIET: Connection to board DTB_WREKRL opened.
[14:14:01.253] 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:
------------------------------------------------------
[14:14:01.256] INFO: RPC call hashes of host and DTB match: 398089610
[14:14:02.804] INFO: DUT info:
[14:14:02.804] INFO: The DUT currently contains the following objects:
[14:14:02.804] INFO: 2 TBM Cores tbm08c (2 ON)
[14:14:02.817] INFO: TBM Core alpha (0): 7 registers set
[14:14:02.817] INFO: TBM Core beta (1): 7 registers set
[14:14:02.818] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:14:02.818] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:02.818] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:02.818] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:02.818] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:02.818] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:02.818] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:02.818] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:02.818] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:02.818] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:02.818] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:02.818] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:02.818] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:02.818] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:02.818] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:02.818] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:02.818] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:03.245] INFO: enter 'restricted' command line mode
[14:14:03.245] INFO: enter test to run
[14:14:47.548] INFO: test: PixelAlive no parameter change
[14:14:47.548] INFO: running: pixelalive
[14:14:47.606] INFO: ----------------------------------------------------------------------
[14:14:47.606] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:14:47.606] INFO: ----------------------------------------------------------------------
[14:14:47.935] INFO: Expecting 41600 events.
[14:14:52.285] INFO: 41600 events read in total (3632ms).
[14:14:52.449] INFO: Test took 4841ms.
[14:14:52.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:14:52.953] INFO: PixTestAlive::aliveTest() done
[14:14:52.953] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[14:14:53.087] INFO: enter test to run
[14:15:29.908] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:15:29.908] INFO: running: highrate
[14:15:29.918] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:15:30.208] INFO: ----------------------------------------------------------------------
[14:15:30.208] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:15:30.208] INFO: ----------------------------------------------------------------------
[14:15:30.208] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:15:30.208] INFO: edge/corner pixel THR is adjusted
[14:15:30.208] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:15:31.167] INFO: Collecting data for 5 seconds...
[14:15:36.186] INFO: Done with hot pixel readout
[14:15:47.590] INFO: PixTest:: pg_setup set to default.
[14:15:47.591] INFO: 0 hot pixels found in step 0
[14:15:47.603] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:15:47.626] INFO: PixTest::trimHotPixels() done
[14:15:47.646] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C0.dat
[14:15:47.670] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C1.dat
[14:15:47.683] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C2.dat
[14:15:47.694] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C3.dat
[14:15:47.704] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C4.dat
[14:15:47.709] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C5.dat
[14:15:47.715] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C6.dat
[14:15:47.720] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C7.dat
[14:15:47.726] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C8.dat
[14:15:47.731] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C9.dat
[14:15:47.736] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C10.dat
[14:15:47.742] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C11.dat
[14:15:47.747] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C12.dat
[14:15:47.753] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C13.dat
[14:15:47.758] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C14.dat
[14:15:47.764] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C15.dat
[14:15:47.769] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//defaultMaskFile.dat
[14:15:47.779] INFO: enter test to run
[14:16:14.660] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:16:14.660] INFO: running: highrate
[14:16:14.664] INFO: ----------------------------------------------------------------------
[14:16:14.664] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:16:14.664] INFO: ----------------------------------------------------------------------
[14:16:14.664] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:16:14.664] INFO: edge/corner pixel THR is adjusted
[14:16:14.664] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:16:15.621] INFO: Collecting data for 1 seconds...
[14:16:16.625] INFO: Done with hot pixel readout
[14:16:20.923] INFO: PixTest:: pg_setup set to default.
[14:16:20.924] INFO: 0 hot pixels found in step 0
[14:16:20.930] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:16:21.020] INFO: PixTest::trimHotPixels() done
[14:16:21.020] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C0.dat
[14:16:21.045] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C1.dat
[14:16:21.052] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C2.dat
[14:16:21.063] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C3.dat
[14:16:21.068] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C4.dat
[14:16:21.074] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C5.dat
[14:16:21.079] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C6.dat
[14:16:21.085] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C7.dat
[14:16:21.091] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C8.dat
[14:16:21.096] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C9.dat
[14:16:21.102] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C10.dat
[14:16:21.107] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C11.dat
[14:16:21.113] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C12.dat
[14:16:21.118] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C13.dat
[14:16:21.124] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C14.dat
[14:16:21.129] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//trimParameters35_C15.dat
[14:16:21.135] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-6-10_FPIXTest-17C-FNAL-160830-1322-150V_2016-08-30_13h23m_1472581388/000_FPIXTest_p17//defaultMaskFile.dat
[14:16:21.147] INFO: enter test to run
[14:16:45.635] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:16:45.636] INFO: running: xray
[14:16:45.637] INFO: ----------------------------------------------------------------------
[14:16:45.637] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:16:45.637] INFO: ----------------------------------------------------------------------
[14:16:46.600] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:16:58.046] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:17:29.011] INFO: Resuming triggers.
[14:17:40.460] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[14:18:10.711] INFO: Resuming triggers.
[14:18:22.161] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:18:52.205] INFO: Resuming triggers.
[14:19:03.653] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[14:19:33.747] INFO: Resuming triggers.
[14:19:45.191] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[14:20:15.144] INFO: Resuming triggers.
[14:20:26.593] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[14:20:56.554] INFO: Resuming triggers.
[14:21:07.002] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[14:21:37.004] INFO: Resuming triggers.
[14:21:49.455] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[14:22:19.435] INFO: Resuming triggers.
[14:22:28.196] INFO: data taking finished, elapsed time: 100 seconds.
[14:22:51.351] INFO: PixTest:: pg_setup set to default.
[14:22:51.354] INFO: PixTestXray::doPhRun() done
[14:22:51.561] INFO: enter test to run
[14:23:30.033] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:23:30.033] INFO: running: xray
[14:23:30.034] INFO: ----------------------------------------------------------------------
[14:23:30.034] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:23:30.034] INFO: ----------------------------------------------------------------------
[14:23:30.997] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:23:37.490] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:24:08.198] INFO: Resuming triggers.
[14:24:14.693] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[14:24:45.483] INFO: Resuming triggers.
[14:24:51.976] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[14:25:22.606] INFO: Resuming triggers.
[14:25:29.099] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[14:25:59.769] INFO: Resuming triggers.
[14:26:06.262] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[14:26:37.539] INFO: Resuming triggers.
[14:26:44.030] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[14:27:15.327] INFO: Resuming triggers.
[14:27:21.815] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[14:27:53.015] INFO: Resuming triggers.
[14:27:59.500] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[14:28:30.398] INFO: Resuming triggers.
[14:28:36.885] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[14:29:07.851] INFO: Resuming triggers.
[14:29:14.334] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[14:29:45.257] INFO: Resuming triggers.
[14:29:51.745] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[14:30:23.662] INFO: Resuming triggers.
[14:30:30.146] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[14:31:01.665] INFO: Resuming triggers.
[14:31:08.153] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[14:31:39.717] INFO: Resuming triggers.
[14:31:46.203] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[14:32:18.028] INFO: Resuming triggers.
[14:32:24.513] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[14:32:55.356] INFO: Resuming triggers.
[14:32:58.386] INFO: data taking finished, elapsed time: 100 seconds.
[14:33:12.972] INFO: PixTest:: pg_setup set to default.
[14:33:12.975] INFO: PixTestXray::doPhRun() done
[14:33:13.123] INFO: enter test to run
[14:33:29.317] INFO: test: HighRate no parameter change
[14:33:29.317] INFO: running: highrate
[14:33:29.340] INFO: ----------------------------------------------------------------------
[14:33:29.340] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:33:29.340] INFO: ----------------------------------------------------------------------
[14:33:29.499] INFO: Expecting 768 events.
[14:33:30.633] INFO: 768 events read in total (419ms).
[14:33:30.633] INFO: Test took 1269ms.
[14:33:31.436] INFO: Expecting 41600 events.
[14:33:34.486] INFO: 41600 events read in total (2523ms).
[14:33:34.487] INFO: Test took 3818ms.
[14:33:34.521] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:35.248] INFO: Expecting 41600 events.
[14:33:38.437] INFO: 41600 events read in total (2662ms).
[14:33:38.438] INFO: Test took 3898ms.
[14:33:38.472] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:39.197] INFO: Expecting 41600 events.
[14:33:42.420] INFO: 41600 events read in total (2696ms).
[14:33:42.421] INFO: Test took 3930ms.
[14:33:42.455] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:43.179] INFO: Expecting 41600 events.
[14:33:46.405] INFO: 41600 events read in total (2699ms).
[14:33:46.406] INFO: Test took 3932ms.
[14:33:46.440] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:47.165] INFO: Expecting 41600 events.
[14:33:50.376] INFO: 41600 events read in total (2684ms).
[14:33:50.377] INFO: Test took 3920ms.
[14:33:50.410] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:51.135] INFO: Expecting 41600 events.
[14:33:54.374] INFO: 41600 events read in total (2712ms).
[14:33:54.375] INFO: Test took 3946ms.
[14:33:54.408] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:55.135] INFO: Expecting 41600 events.
[14:33:58.378] INFO: 41600 events read in total (2716ms).
[14:33:58.378] INFO: Test took 3952ms.
[14:33:58.412] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:59.133] INFO: Expecting 41600 events.
[14:34:02.376] INFO: 41600 events read in total (2716ms).
[14:34:02.377] INFO: Test took 3946ms.
[14:34:02.410] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:03.135] INFO: Expecting 41600 events.
[14:34:06.372] INFO: 41600 events read in total (2710ms).
[14:34:06.373] INFO: Test took 3944ms.
[14:34:06.407] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:07.130] INFO: Expecting 41600 events.
[14:34:10.386] INFO: 41600 events read in total (2730ms).
[14:34:10.387] INFO: Test took 3961ms.
[14:34:10.421] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:11.148] INFO: Expecting 41600 events.
[14:34:14.391] INFO: 41600 events read in total (2716ms).
[14:34:14.392] INFO: Test took 3952ms.
[14:34:14.425] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:15.153] INFO: Expecting 41600 events.
[14:34:18.372] INFO: 41600 events read in total (2692ms).
[14:34:18.373] INFO: Test took 3929ms.
[14:34:18.407] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:19.132] INFO: Expecting 41600 events.
[14:34:22.299] INFO: 41600 events read in total (2641ms).
[14:34:22.300] INFO: Test took 3876ms.
[14:34:22.334] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:23.060] INFO: Expecting 41600 events.
[14:34:26.306] INFO: 41600 events read in total (2719ms).
[14:34:26.307] INFO: Test took 3953ms.
[14:34:26.341] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:27.065] INFO: Expecting 41600 events.
[14:34:30.275] INFO: 41600 events read in total (2683ms).
[14:34:30.276] INFO: Test took 3918ms.
[14:34:30.310] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:31.031] INFO: Expecting 41600 events.
[14:34:34.209] INFO: 41600 events read in total (2651ms).
[14:34:34.210] INFO: Test took 3881ms.
[14:34:34.243] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:34.966] INFO: Expecting 41600 events.
[14:34:38.207] INFO: 41600 events read in total (2714ms).
[14:34:38.208] INFO: Test took 3947ms.
[14:34:38.242] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:38.964] INFO: Expecting 41600 events.
[14:34:42.156] INFO: 41600 events read in total (2665ms).
[14:34:42.157] INFO: Test took 3896ms.
[14:34:42.191] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:42.915] INFO: Expecting 41600 events.
[14:34:46.151] INFO: 41600 events read in total (2709ms).
[14:34:46.152] INFO: Test took 3944ms.
[14:34:46.186] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:46.910] INFO: Expecting 41600 events.
[14:34:50.023] INFO: 41600 events read in total (2587ms).
[14:34:50.024] INFO: Test took 3820ms.
[14:34:50.057] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:50.422] INFO: enter test to run
[14:35:20.924] INFO: test: HighRate no parameter change
[14:35:20.924] INFO: running: highrate
[14:35:20.925] INFO: ----------------------------------------------------------------------
[14:35:20.925] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:35:20.925] INFO: ----------------------------------------------------------------------
[14:35:21.550] INFO: Expecting 208000 events.
[14:35:33.407] INFO: 208000 events read in total (11330ms).
[14:35:33.410] INFO: Test took 12461ms.
[14:35:33.556] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:33.808] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[14:35:33.808] INFO: number of red-efficiency pixels: 79 46 86 111 145 125 170 84 83 140 121 92 79 61 25 37
[14:35:33.808] INFO: number of X-ray hits detected: 64016 41005 68225 99723 107705 112155 111133 83097 82137 109031 97774 83916 86007 52039 22739 27054
[14:35:33.808] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:35:33.808] INFO: number of Vcal hits detected: 207920 207953 207912 207885 207802 207871 207827 207916 207916 207858 207876 207908 207918 207933 207974 207962
[14:35:33.808] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[14:35:33.808] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[14:35:33.808] INFO: X-ray hit rate [MHz/cm2]: 18.8 12.0 20.0 29.2 31.6 32.9 32.6 24.4 24.1 32.0 28.7 24.6 25.2 15.3 6.7 7.9
[14:35:33.808] INFO: PixTestHighRate::doXPixelAlive() done
[14:35:33.853] INFO: PixTest:: pg_setup set to default.
[14:35:33.868] INFO: enter test to run
[14:35:55.332] INFO: test: HighRate no parameter change
[14:35:55.332] INFO: running: highrate
[14:35:55.333] INFO: ----------------------------------------------------------------------
[14:35:55.333] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:35:55.333] INFO: ----------------------------------------------------------------------
[14:35:55.947] INFO: Expecting 208000 events.
[14:36:09.670] INFO: 208000 events read in total (13196ms).
[14:36:09.676] INFO: Test took 14334ms.
[14:36:09.977] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:10.280] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[14:36:10.280] INFO: number of red-efficiency pixels: 251 129 306 365 549 444 630 301 268 452 426 347 279 173 55 67
[14:36:10.280] INFO: number of X-ray hits detected: 137796 87405 145950 213534 231406 241462 238208 177734 177192 234246 208834 180012 184723 112342 48639 58428
[14:36:10.280] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:36:10.280] INFO: number of Vcal hits detected: 207726 207866 207653 207608 207341 207507 207301 207671 207718 207497 207532 207635 207712 207784 207943 207932
[14:36:10.280] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.8 99.7 99.9 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[14:36:10.281] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.8 99.7 99.8 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[14:36:10.281] INFO: X-ray hit rate [MHz/cm2]: 40.4 25.6 42.8 62.6 67.8 70.8 69.8 52.1 51.9 68.7 61.2 52.8 54.1 32.9 14.3 17.1
[14:36:10.281] INFO: PixTestHighRate::doXPixelAlive() done
[14:36:10.326] INFO: PixTest:: pg_setup set to default.
[14:36:10.345] INFO: enter test to run
[14:36:31.355] INFO: test: HighRate no parameter change
[14:36:31.356] INFO: running: highrate
[14:36:31.357] INFO: ----------------------------------------------------------------------
[14:36:31.357] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:36:31.357] INFO: ----------------------------------------------------------------------
[14:36:31.976] INFO: Expecting 208000 events.
[14:36:47.687] INFO: 208000 events read in total (15184ms).
[14:36:47.695] INFO: Test took 16329ms.
[14:36:48.155] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:48.509] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[14:36:48.509] INFO: number of red-efficiency pixels: 519 271 707 806 1389 1110 1430 656 518 1126 1071 809 645 385 93 116
[14:36:48.509] INFO: number of X-ray hits detected: 208144 133941 220758 322946 348294 363517 359858 269992 267838 354699 317502 273254 280275 170690 74476 88578
[14:36:48.509] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:36:48.509] INFO: number of Vcal hits detected: 207360 207702 207033 207039 206137 206579 206025 207191 207384 206553 206648 207039 207238 207508 207903 207882
[14:36:48.509] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.6 99.6 99.2 99.4 99.2 99.7 99.7 99.4 99.4 99.6 99.7 99.8 100.0 99.9
[14:36:48.509] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.5 99.5 99.1 99.3 99.1 99.6 99.7 99.3 99.4 99.5 99.6 99.8 100.0 99.9
[14:36:48.509] INFO: X-ray hit rate [MHz/cm2]: 61.0 39.3 64.7 94.7 102.1 106.5 105.5 79.1 78.5 104.0 93.1 80.1 82.2 50.0 21.8 26.0
[14:36:48.509] INFO: PixTestHighRate::doXPixelAlive() done
[14:36:48.559] INFO: PixTest:: pg_setup set to default.
[14:36:48.573] INFO: enter test to run
[14:37:07.971] INFO: test: exit no parameter change
[14:37:08.717] QUIET: Connection to board 33 closed.
[14:37:08.905] INFO: pXar: this is the end, my friend