[09:56:25.598] INFO: *** Welcome to pxar ***
[09:56:25.598] INFO: *** Today: 2016/04/14
[09:56:25.618] INFO: *** Version: v1.9.0-796-gef167-dirty
[09:56:25.618] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//dacParameters35_C15.dat
[09:56:25.622] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//tbmParameters_C0b.dat
[09:56:25.622] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//defaultMaskFile.dat
[09:56:25.622] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C15.dat
[09:56:25.701] INFO: clk: 4
[09:56:25.701] INFO: ctr: 4
[09:56:25.701] INFO: sda: 19
[09:56:25.701] INFO: tin: 9
[09:56:25.701] INFO: level: 15
[09:56:25.701] INFO: triggerdelay: 0
[09:56:25.701] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[09:56:25.701] INFO: Log level: INFO
[09:56:25.719] QUIET: Connection to board DTB_WREKRL opened.
[09:56:25.722] 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:
------------------------------------------------------
[09:56:25.725] INFO: RPC call hashes of host and DTB match: 398089610
[09:56:27.256] INFO: DUT info:
[09:56:27.256] INFO: The DUT currently contains the following objects:
[09:56:27.256] INFO: 2 TBM Cores tbm08c (2 ON)
[09:56:27.256] INFO: TBM Core alpha (0): 7 registers set
[09:56:27.256] INFO: TBM Core beta (1): 7 registers set
[09:56:27.256] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:56:27.256] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.256] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.256] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.256] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.256] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.256] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.256] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.256] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.256] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.256] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.256] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.256] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.256] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.256] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.256] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.257] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:27.660] INFO: enter 'restricted' command line mode
[09:56:27.660] INFO: enter test to run
[09:56:36.273] INFO: test: PixelAlive no parameter change
[09:56:36.273] INFO: running: pixelalive
[09:56:36.282] INFO: ----------------------------------------------------------------------
[09:56:36.282] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:56:36.282] INFO: ----------------------------------------------------------------------
[09:56:36.599] INFO: Expecting 41600 events.
[09:56:40.927] INFO: 41600 events read in total (3610ms).
[09:56:41.095] INFO: Test took 4810ms.
[09:56:41.107] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:41.367] INFO: PixTestAlive::aliveTest() done
[09:56:41.367] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:56:41.402] INFO: enter test to run
[09:57:14.472] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[09:57:14.473] INFO: running: highrate
[09:57:14.473] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[09:57:14.714] INFO: ----------------------------------------------------------------------
[09:57:14.715] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[09:57:14.715] INFO: ----------------------------------------------------------------------
[09:57:14.715] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[09:57:14.715] INFO: edge/corner pixel THR is adjusted
[09:57:14.715] INFO: PixTestHighRate::trimHotPixels: step 0...
[09:57:15.674] INFO: Collecting data for 5 seconds...
[09:57:20.691] INFO: Done with hot pixel readout
[09:57:32.095] INFO: PixTest:: pg_setup set to default.
[09:57:32.096] INFO: 1 hot pixels found in step 0
[09:57:33.087] INFO: Collecting data for 5 seconds...
[09:57:38.104] INFO: Done with hot pixel readout
[09:57:49.642] INFO: PixTest:: pg_setup set to default.
[09:57:49.643] INFO: 0 hot pixels found in step 1
[09:57:49.677] INFO: 0 hot pixels could not be trimmed and have been masked.
[09:57:49.681] INFO: PixTest::trimHotPixels() done
[09:57:49.681] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C0.dat
[09:57:49.686] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C1.dat
[09:57:49.693] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C2.dat
[09:57:49.699] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C3.dat
[09:57:49.704] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C4.dat
[09:57:49.709] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C5.dat
[09:57:49.715] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C6.dat
[09:57:49.720] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C7.dat
[09:57:49.725] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C8.dat
[09:57:49.730] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C9.dat
[09:57:49.736] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C10.dat
[09:57:49.741] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C11.dat
[09:57:49.746] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C12.dat
[09:57:49.752] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C13.dat
[09:57:49.757] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C14.dat
[09:57:49.762] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C15.dat
[09:57:49.767] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//defaultMaskFile.dat
[09:57:49.777] INFO: enter test to run
[10:00:04.239] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:00:04.239] INFO: running: highrate
[10:00:04.244] INFO: ----------------------------------------------------------------------
[10:00:04.244] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:00:04.244] INFO: ----------------------------------------------------------------------
[10:00:04.244] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:00:04.244] INFO: edge/corner pixel THR is adjusted
[10:00:04.244] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:00:05.206] INFO: Collecting data for 1 seconds...
[10:00:06.209] INFO: Done with hot pixel readout
[10:00:10.249] INFO: PixTest:: pg_setup set to default.
[10:00:10.250] INFO: 0 hot pixels found in step 0
[10:00:10.255] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:00:10.339] INFO: PixTest::trimHotPixels() done
[10:00:10.340] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C0.dat
[10:00:10.351] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C1.dat
[10:00:10.356] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C2.dat
[10:00:10.361] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C3.dat
[10:00:10.366] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C4.dat
[10:00:10.372] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C5.dat
[10:00:10.377] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C6.dat
[10:00:10.382] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C7.dat
[10:00:10.387] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C8.dat
[10:00:10.393] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C9.dat
[10:00:10.398] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C10.dat
[10:00:10.403] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C11.dat
[10:00:10.409] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C12.dat
[10:00:10.414] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C13.dat
[10:00:10.419] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C14.dat
[10:00:10.424] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//trimParameters35_C15.dat
[10:00:10.430] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-3-26_FPIXTest-17C-FNAL-160412-1450_2016-04-12_14h50m_1460490629/000_FPIXTest_p17//defaultMaskFile.dat
[10:00:10.440] INFO: enter test to run
[10:00:33.975] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:00:33.975] INFO: running: xray
[10:00:33.976] INFO: ----------------------------------------------------------------------
[10:00:33.976] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:00:33.976] INFO: ----------------------------------------------------------------------
[10:00:34.939] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:00:46.407] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:01:15.758] INFO: Resuming triggers.
[10:01:27.236] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[10:01:56.563] INFO: Resuming triggers.
[10:02:08.038] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:02:37.465] INFO: Resuming triggers.
[10:02:48.939] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:03:18.230] INFO: Resuming triggers.
[10:03:29.705] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[10:03:59.157] INFO: Resuming triggers.
[10:04:10.633] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[10:04:40.011] INFO: Resuming triggers.
[10:04:51.488] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:05:20.827] INFO: Resuming triggers.
[10:05:32.302] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[10:06:01.594] INFO: Resuming triggers.
[10:06:10.121] INFO: data taking finished, elapsed time: 100 seconds.
[10:06:32.072] INFO: PixTest:: pg_setup set to default.
[10:06:32.075] INFO: PixTestXray::doPhRun() done
[10:06:32.249] INFO: enter test to run
[10:08:32.260] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:08:32.260] INFO: running: xray
[10:08:32.261] INFO: ----------------------------------------------------------------------
[10:08:32.261] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:08:32.261] INFO: ----------------------------------------------------------------------
[10:08:33.232] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:08:39.806] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:09:09.912] INFO: Resuming triggers.
[10:09:16.484] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:09:44.739] INFO: Resuming triggers.
[10:09:51.311] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[10:10:21.375] INFO: Resuming triggers.
[10:10:27.951] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[10:10:58.078] INFO: Resuming triggers.
[10:11:04.651] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[10:11:34.889] INFO: Resuming triggers.
[10:11:41.460] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[10:12:11.575] INFO: Resuming triggers.
[10:12:18.146] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:12:48.306] INFO: Resuming triggers.
[10:12:54.880] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[10:13:24.981] INFO: Resuming triggers.
[10:13:31.558] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[10:14:01.612] INFO: Resuming triggers.
[10:14:08.186] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[10:14:38.215] INFO: Resuming triggers.
[10:14:44.788] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[10:15:14.905] INFO: Resuming triggers.
[10:15:21.480] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[10:15:51.614] INFO: Resuming triggers.
[10:15:58.188] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[10:16:28.319] INFO: Resuming triggers.
[10:16:34.894] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[10:17:04.960] INFO: Resuming triggers.
[10:17:11.533] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[10:17:41.519] INFO: Resuming triggers.
[10:17:43.244] INFO: data taking finished, elapsed time: 100 seconds.
[10:17:51.495] INFO: PixTest:: pg_setup set to default.
[10:17:51.499] INFO: PixTestXray::doPhRun() done
[10:17:51.651] INFO: enter test to run
[10:18:48.575] INFO: test: HighRate no parameter change
[10:18:48.575] INFO: running: highrate
[10:18:48.576] INFO: ----------------------------------------------------------------------
[10:18:48.576] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:18:48.576] INFO: ----------------------------------------------------------------------
[10:18:48.717] INFO: Expecting 768 events.
[10:18:49.851] INFO: 768 events read in total (419ms).
[10:18:49.851] INFO: Test took 1268ms.
[10:18:50.654] INFO: Expecting 41600 events.
[10:18:53.688] INFO: 41600 events read in total (2507ms).
[10:18:53.689] INFO: Test took 3829ms.
[10:18:53.720] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:54.454] INFO: Expecting 41600 events.
[10:18:57.613] INFO: 41600 events read in total (2632ms).
[10:18:57.614] INFO: Test took 3878ms.
[10:18:57.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:58.375] INFO: Expecting 41600 events.
[10:19:01.583] INFO: 41600 events read in total (2681ms).
[10:19:01.584] INFO: Test took 3921ms.
[10:19:01.615] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:02.341] INFO: Expecting 41600 events.
[10:19:05.545] INFO: 41600 events read in total (2677ms).
[10:19:05.546] INFO: Test took 3914ms.
[10:19:05.579] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:06.305] INFO: Expecting 41600 events.
[10:19:09.528] INFO: 41600 events read in total (2697ms).
[10:19:09.529] INFO: Test took 3932ms.
[10:19:09.561] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:10.285] INFO: Expecting 41600 events.
[10:19:13.500] INFO: 41600 events read in total (2688ms).
[10:19:13.501] INFO: Test took 3923ms.
[10:19:13.533] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:14.258] INFO: Expecting 41600 events.
[10:19:17.472] INFO: 41600 events read in total (2687ms).
[10:19:17.473] INFO: Test took 3923ms.
[10:19:17.506] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:18.230] INFO: Expecting 41600 events.
[10:19:21.457] INFO: 41600 events read in total (2701ms).
[10:19:21.458] INFO: Test took 3927ms.
[10:19:21.490] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:22.217] INFO: Expecting 41600 events.
[10:19:25.442] INFO: 41600 events read in total (2698ms).
[10:19:25.443] INFO: Test took 3935ms.
[10:19:25.475] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:26.203] INFO: Expecting 41600 events.
[10:19:29.425] INFO: 41600 events read in total (2695ms).
[10:19:29.427] INFO: Test took 3934ms.
[10:19:29.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:30.186] INFO: Expecting 41600 events.
[10:19:33.413] INFO: 41600 events read in total (2700ms).
[10:19:33.414] INFO: Test took 3937ms.
[10:19:33.445] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:34.176] INFO: Expecting 41600 events.
[10:19:37.389] INFO: 41600 events read in total (2686ms).
[10:19:37.390] INFO: Test took 3927ms.
[10:19:37.422] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:38.145] INFO: Expecting 41600 events.
[10:19:40.934] INFO: 41600 events read in total (2262ms).
[10:19:40.934] INFO: Test took 3494ms.
[10:19:40.967] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:41.698] INFO: Expecting 41600 events.
[10:19:44.507] INFO: 41600 events read in total (2282ms).
[10:19:44.508] INFO: Test took 3524ms.
[10:19:44.540] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:45.265] INFO: Expecting 41600 events.
[10:19:48.057] INFO: 41600 events read in total (2266ms).
[10:19:48.058] INFO: Test took 3500ms.
[10:19:48.090] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:48.818] INFO: Expecting 41600 events.
[10:19:51.640] INFO: 41600 events read in total (2296ms).
[10:19:51.641] INFO: Test took 3533ms.
[10:19:51.674] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:52.403] INFO: Expecting 41600 events.
[10:19:55.348] INFO: 41600 events read in total (2418ms).
[10:19:55.349] INFO: Test took 3657ms.
[10:19:55.380] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:56.110] INFO: Expecting 41600 events.
[10:19:59.341] INFO: 41600 events read in total (2704ms).
[10:19:59.342] INFO: Test took 3944ms.
[10:19:59.373] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:00.096] INFO: Expecting 41600 events.
[10:20:03.272] INFO: 41600 events read in total (2649ms).
[10:20:03.273] INFO: Test took 3882ms.
[10:20:03.305] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:04.031] INFO: Expecting 41600 events.
[10:20:07.114] INFO: 41600 events read in total (2556ms).
[10:20:07.115] INFO: Test took 3791ms.
[10:20:07.145] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:07.511] INFO: enter test to run
[10:22:08.222] INFO: test: HighRate no parameter change
[10:22:08.222] INFO: running: highrate
[10:22:08.223] INFO: ----------------------------------------------------------------------
[10:22:08.223] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:22:08.223] INFO: ----------------------------------------------------------------------
[10:22:08.834] INFO: Expecting 208000 events.
[10:22:20.600] INFO: 208000 events read in total (11239ms).
[10:22:20.603] INFO: Test took 12370ms.
[10:22:20.735] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:20.982] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:22:20.982] INFO: number of red-efficiency pixels: 87 41 80 152 137 123 126 101 81 103 97 87 104 58 28 24
[10:22:20.982] INFO: number of X-ray hits detected: 60246 40951 64315 97930 102957 107060 105713 71527 71684 87623 83724 73536 77329 48775 21455 25594
[10:22:20.982] 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:22:20.982] INFO: number of Vcal hits detected: 207912 207959 207870 207845 207860 207873 207867 207898 207919 207896 207902 207909 207895 207941 207972 207976
[10:22:20.983] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[10:22:20.983] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0
[10:22:20.983] INFO: X-ray hit rate [MHz/cm2]: 17.7 12.0 18.9 28.7 30.2 31.4 31.0 21.0 21.0 25.7 24.5 21.6 22.7 14.3 6.3 7.5
[10:22:20.983] INFO: PixTestHighRate::doXPixelAlive() done
[10:22:21.030] INFO: PixTest:: pg_setup set to default.
[10:22:21.044] INFO: enter test to run
[10:22:44.053] INFO: test: HighRate no parameter change
[10:22:44.053] INFO: running: highrate
[10:22:44.054] INFO: ----------------------------------------------------------------------
[10:22:44.054] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:22:44.054] INFO: ----------------------------------------------------------------------
[10:22:44.666] INFO: Expecting 208000 events.
[10:22:58.224] INFO: 208000 events read in total (13031ms).
[10:22:58.229] INFO: Test took 14166ms.
[10:22:58.516] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:58.813] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:22:58.813] INFO: number of red-efficiency pixels: 257 124 291 515 411 575 422 312 246 290 360 301 321 193 68 64
[10:22:58.813] INFO: number of X-ray hits detected: 134821 92613 143999 218886 229071 238130 235357 160449 161257 194289 186412 164386 172576 108323 48023 57204
[10:22:58.813] 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:22:58.813] INFO: number of Vcal hits detected: 207731 207873 207632 207454 207560 207365 207530 207669 207738 207688 207624 207691 207666 207798 207931 207934
[10:22:58.813] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.8 99.9 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[10:22:58.813] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.8 99.7 99.8 99.8 99.9 99.9 99.8 99.9 99.8 99.9 100.0 100.0
[10:22:58.813] INFO: X-ray hit rate [MHz/cm2]: 39.5 27.1 42.2 64.2 67.1 69.8 69.0 47.0 47.3 56.9 54.6 48.2 50.6 31.8 14.1 16.8
[10:22:58.813] INFO: PixTestHighRate::doXPixelAlive() done
[10:22:58.861] INFO: PixTest:: pg_setup set to default.
[10:22:58.872] INFO: enter test to run
[10:23:13.061] INFO: test: HighRate no parameter change
[10:23:13.061] INFO: running: highrate
[10:23:13.062] INFO: ----------------------------------------------------------------------
[10:23:13.062] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:23:13.062] INFO: ----------------------------------------------------------------------
[10:23:13.679] INFO: Expecting 208000 events.
[10:23:29.468] INFO: 208000 events read in total (15262ms).
[10:23:29.475] INFO: Test took 16403ms.
[10:23:29.949] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:30.303] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:23:30.303] INFO: number of red-efficiency pixels: 558 255 746 1262 1051 1480 1100 828 513 763 941 718 736 492 115 110
[10:23:30.303] INFO: number of X-ray hits detected: 215713 147376 231190 349678 365443 381122 376509 256504 258433 310856 297550 263858 275991 173694 76735 91436
[10:23:30.303] 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:23:30.303] INFO: number of Vcal hits detected: 207316 207727 206906 206364 206699 205954 206618 206938 207436 207110 206862 207146 207157 207434 207881 207886
[10:23:30.303] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.6 99.3 99.4 99.1 99.4 99.5 99.7 99.6 99.5 99.6 99.6 99.7 99.9 99.9
[10:23:30.303] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.5 99.2 99.4 99.0 99.3 99.5 99.7 99.6 99.5 99.6 99.6 99.7 99.9 99.9
[10:23:30.303] INFO: X-ray hit rate [MHz/cm2]: 63.2 43.2 67.8 102.5 107.1 111.7 110.4 75.2 75.7 91.1 87.2 77.3 80.9 50.9 22.5 26.8
[10:23:30.303] INFO: PixTestHighRate::doXPixelAlive() done
[10:23:30.352] INFO: PixTest:: pg_setup set to default.
[10:23:30.369] INFO: enter test to run
[10:23:35.277] INFO: test: exit no parameter change
[10:23:35.660] QUIET: Connection to board 33 closed.
[10:23:35.660] INFO: pXar: this is the end, my friend