[10:32:22.687] INFO: *** Welcome to pxar ***
[10:32:22.687] INFO: *** Today: 2016/04/22
[10:32:22.712] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:32:22.712] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//dacParameters35_C15.dat
[10:32:22.715] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:32:22.715] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//defaultMaskFile.dat
[10:32:22.716] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C15.dat
[10:32:22.825] INFO: clk: 4
[10:32:22.825] INFO: ctr: 4
[10:32:22.825] INFO: sda: 19
[10:32:22.825] INFO: tin: 9
[10:32:22.825] INFO: level: 15
[10:32:22.825] INFO: triggerdelay: 0
[10:32:22.825] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:32:22.825] INFO: Log level: INFO
[10:32:22.843] QUIET: Connection to board DTB_WREK4U opened.
[10:32:22.847] 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:32:22.849] INFO: RPC call hashes of host and DTB match: 398089610
[10:32:24.381] INFO: DUT info:
[10:32:24.381] INFO: The DUT currently contains the following objects:
[10:32:24.381] INFO: 2 TBM Cores tbm08c (2 ON)
[10:32:24.381] INFO: TBM Core alpha (0): 7 registers set
[10:32:24.381] INFO: TBM Core beta (1): 7 registers set
[10:32:24.381] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:32:24.381] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.381] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.381] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.381] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.381] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.381] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.381] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.381] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.381] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.381] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.381] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.381] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.381] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.381] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.381] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.381] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:32:24.785] INFO: enter 'restricted' command line mode
[10:32:24.785] INFO: enter test to run
[10:32:27.108] INFO: test: PixelAlive no parameter change
[10:32:27.108] INFO: running: pixelalive
[10:32:27.117] INFO: ----------------------------------------------------------------------
[10:32:27.117] 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:32:27.117] INFO: ----------------------------------------------------------------------
[10:32:27.442] INFO: Expecting 41600 events.
[10:32:31.771] INFO: 41600 events read in total (3610ms).
[10:32:31.938] INFO: Test took 4819ms.
[10:32:31.948] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:32:32.213] INFO: PixTestAlive::aliveTest() done
[10:32:32.213] INFO: number of dead pixels (per ROC): 0 0 0 3 0 0 0 0 0 0 0 0 0 0 0 0
[10:32:32.246] INFO: enter test to run
[10:32:47.795] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:32:47.795] INFO: running: highrate
[10:32:47.796] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:32:48.011] INFO: ----------------------------------------------------------------------
[10:32:48.011] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:32:48.011] INFO: ----------------------------------------------------------------------
[10:32:48.011] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:32:48.011] INFO: edge/corner pixel THR is adjusted
[10:32:48.011] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:32:48.973] INFO: Collecting data for 5 seconds...
[10:32:53.991] INFO: Done with hot pixel readout
[10:33:05.347] INFO: PixTest:: pg_setup set to default.
[10:33:05.348] INFO: 1 hot pixels found in step 0
[10:33:06.338] INFO: Collecting data for 5 seconds...
[10:33:11.353] INFO: Done with hot pixel readout
[10:33:22.005] INFO: PixTest:: pg_setup set to default.
[10:33:23.006] INFO: 3 hot pixels found in step 1
[10:33:23.002] INFO: Collecting data for 5 seconds...
[10:33:29.019] INFO: Done with hot pixel readout
[10:33:40.692] INFO: PixTest:: pg_setup set to default.
[10:33:40.693] INFO: 0 hot pixels found in step 2
[10:33:40.731] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:33:40.735] INFO: PixTest::trimHotPixels() done
[10:33:40.735] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C0.dat
[10:33:40.741] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C1.dat
[10:33:40.747] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C2.dat
[10:33:40.752] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C3.dat
[10:33:40.758] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C4.dat
[10:33:40.763] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C5.dat
[10:33:40.768] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C6.dat
[10:33:40.774] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C7.dat
[10:33:40.779] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C8.dat
[10:33:40.784] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C9.dat
[10:33:40.790] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C10.dat
[10:33:40.795] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C11.dat
[10:33:40.801] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C12.dat
[10:33:40.806] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C13.dat
[10:33:40.811] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C14.dat
[10:33:40.817] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C15.dat
[10:33:40.822] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//defaultMaskFile.dat
[10:33:40.832] INFO: enter test to run
[10:34:00.795] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:34:00.795] INFO: running: highrate
[10:34:00.799] INFO: ----------------------------------------------------------------------
[10:34:00.799] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:34:00.799] INFO: ----------------------------------------------------------------------
[10:34:00.799] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:34:00.799] INFO: edge/corner pixel THR is adjusted
[10:34:00.799] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:34:01.758] INFO: Collecting data for 1 seconds...
[10:34:02.762] INFO: Done with hot pixel readout
[10:34:06.790] INFO: PixTest:: pg_setup set to default.
[10:34:06.791] INFO: 0 hot pixels found in step 0
[10:34:06.796] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:34:06.888] INFO: PixTest::trimHotPixels() done
[10:34:06.888] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C0.dat
[10:34:06.896] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C1.dat
[10:34:06.901] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C2.dat
[10:34:06.906] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C3.dat
[10:34:06.912] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C4.dat
[10:34:06.917] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C5.dat
[10:34:06.922] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C6.dat
[10:34:06.928] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C7.dat
[10:34:06.933] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C8.dat
[10:34:06.938] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C9.dat
[10:34:06.943] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C10.dat
[10:34:06.949] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C11.dat
[10:34:06.954] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C12.dat
[10:34:06.959] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C13.dat
[10:34:06.964] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C14.dat
[10:34:06.970] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//trimParameters35_C15.dat
[10:34:06.975] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-2-26_FPIXTest-17C-FNAL-160419-1248_2016-04-19_12h48m_1461088125/000_FPIXTest_p17//defaultMaskFile.dat
[10:34:06.985] INFO: enter test to run
[10:34:24.347] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:34:24.347] INFO: running: xray
[10:34:24.348] INFO: ----------------------------------------------------------------------
[10:34:24.348] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:34:24.348] INFO: ----------------------------------------------------------------------
[10:34:25.312] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:34:36.826] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:35:06.112] INFO: Resuming triggers.
[10:35:17.624] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[10:35:46.817] INFO: Resuming triggers.
[10:35:58.333] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:36:27.669] INFO: Resuming triggers.
[10:36:39.184] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:37:08.470] INFO: Resuming triggers.
[10:37:19.986] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[10:37:47.079] INFO: Resuming triggers.
[10:37:58.597] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[10:38:27.989] INFO: Resuming triggers.
[10:38:39.509] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:39:08.724] INFO: Resuming triggers.
[10:39:20.245] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[10:39:49.498] INFO: Resuming triggers.
[10:39:57.679] INFO: data taking finished, elapsed time: 100 seconds.
[10:40:18.521] INFO: PixTest:: pg_setup set to default.
[10:40:18.524] INFO: PixTestXray::doPhRun() done
[10:40:18.691] INFO: enter test to run
[10:40:42.840] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:40:42.840] INFO: running: xray
[10:40:42.841] INFO: ----------------------------------------------------------------------
[10:40:42.841] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:40:42.841] INFO: ----------------------------------------------------------------------
[10:40:43.805] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:40:50.436] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:41:20.526] INFO: Resuming triggers.
[10:41:27.168] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:41:57.704] INFO: Resuming triggers.
[10:42:04.339] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[10:42:34.335] INFO: Resuming triggers.
[10:42:40.971] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[10:43:10.901] INFO: Resuming triggers.
[10:43:17.532] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:43:47.519] INFO: Resuming triggers.
[10:43:54.152] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[10:44:24.279] INFO: Resuming triggers.
[10:44:30.906] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:45:01.026] INFO: Resuming triggers.
[10:45:07.651] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[10:45:37.700] INFO: Resuming triggers.
[10:45:44.331] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[10:46:14.438] INFO: Resuming triggers.
[10:46:21.068] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[10:46:51.155] INFO: Resuming triggers.
[10:46:57.788] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[10:47:26.937] INFO: Resuming triggers.
[10:47:33.572] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[10:48:03.673] INFO: Resuming triggers.
[10:48:10.304] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[10:48:40.424] INFO: Resuming triggers.
[10:48:47.056] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:49:17.187] INFO: Resuming triggers.
[10:49:23.825] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[10:49:53.896] INFO: Resuming triggers.
[10:49:54.732] INFO: data taking finished, elapsed time: 100 seconds.
[10:49:58.893] INFO: PixTest:: pg_setup set to default.
[10:49:58.896] INFO: PixTestXray::doPhRun() done
[10:49:59.048] INFO: enter test to run
[10:50:37.451] INFO: test: HighRate no parameter change
[10:50:37.451] INFO: running: highrate
[10:50:37.453] INFO: ----------------------------------------------------------------------
[10:50:37.453] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:50:37.453] INFO: ----------------------------------------------------------------------
[10:50:37.593] INFO: Expecting 768 events.
[10:50:38.726] INFO: 768 events read in total (418ms).
[10:50:38.727] INFO: Test took 1268ms.
[10:50:39.530] INFO: Expecting 41600 events.
[10:50:42.576] INFO: 41600 events read in total (2520ms).
[10:50:42.577] INFO: Test took 3844ms.
[10:50:42.612] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:43.339] INFO: Expecting 41600 events.
[10:50:46.549] INFO: 41600 events read in total (2684ms).
[10:50:46.550] INFO: Test took 3920ms.
[10:50:46.585] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:47.307] INFO: Expecting 41600 events.
[10:50:50.546] INFO: 41600 events read in total (2713ms).
[10:50:50.547] INFO: Test took 3943ms.
[10:50:50.582] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:51.302] INFO: Expecting 41600 events.
[10:50:54.543] INFO: 41600 events read in total (2715ms).
[10:50:54.544] INFO: Test took 3944ms.
[10:50:54.578] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:55.299] INFO: Expecting 41600 events.
[10:50:58.540] INFO: 41600 events read in total (2714ms).
[10:50:58.541] INFO: Test took 3945ms.
[10:50:58.576] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:59.299] INFO: Expecting 41600 events.
[10:51:02.538] INFO: 41600 events read in total (2712ms).
[10:51:02.539] INFO: Test took 3945ms.
[10:51:02.573] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:03.297] INFO: Expecting 41600 events.
[10:51:06.544] INFO: 41600 events read in total (2720ms).
[10:51:06.545] INFO: Test took 3953ms.
[10:51:06.580] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:07.301] INFO: Expecting 41600 events.
[10:51:10.541] INFO: 41600 events read in total (2713ms).
[10:51:10.542] INFO: Test took 3945ms.
[10:51:10.576] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:11.300] INFO: Expecting 41600 events.
[10:51:14.548] INFO: 41600 events read in total (2721ms).
[10:51:14.549] INFO: Test took 3955ms.
[10:51:14.584] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:15.304] INFO: Expecting 41600 events.
[10:51:18.546] INFO: 41600 events read in total (2715ms).
[10:51:18.547] INFO: Test took 3945ms.
[10:51:18.581] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:19.306] INFO: Expecting 41600 events.
[10:51:22.550] INFO: 41600 events read in total (2717ms).
[10:51:22.551] INFO: Test took 3951ms.
[10:51:22.586] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:23.306] INFO: Expecting 41600 events.
[10:51:26.564] INFO: 41600 events read in total (2731ms).
[10:51:26.565] INFO: Test took 3960ms.
[10:51:26.599] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:27.324] INFO: Expecting 41600 events.
[10:51:30.568] INFO: 41600 events read in total (2717ms).
[10:51:30.569] INFO: Test took 3951ms.
[10:51:30.604] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:31.326] INFO: Expecting 41600 events.
[10:51:34.583] INFO: 41600 events read in total (2730ms).
[10:51:34.584] INFO: Test took 3963ms.
[10:51:34.619] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:35.345] INFO: Expecting 41600 events.
[10:51:38.595] INFO: 41600 events read in total (2723ms).
[10:51:38.596] INFO: Test took 3958ms.
[10:51:38.631] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:39.352] INFO: Expecting 41600 events.
[10:51:42.595] INFO: 41600 events read in total (2716ms).
[10:51:42.596] INFO: Test took 3947ms.
[10:51:42.630] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:43.356] INFO: Expecting 41600 events.
[10:51:46.613] INFO: 41600 events read in total (2730ms).
[10:51:46.614] INFO: Test took 3965ms.
[10:51:46.649] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:47.372] INFO: Expecting 41600 events.
[10:51:50.642] INFO: 41600 events read in total (2743ms).
[10:51:50.643] INFO: Test took 3974ms.
[10:51:50.677] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:51.399] INFO: Expecting 41600 events.
[10:51:54.631] INFO: 41600 events read in total (2705ms).
[10:51:54.632] INFO: Test took 3935ms.
[10:51:54.667] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:55.392] INFO: Expecting 41600 events.
[10:51:58.538] INFO: 41600 events read in total (2620ms).
[10:51:58.539] INFO: Test took 3854ms.
[10:51:58.573] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:58.934] INFO: enter test to run
[10:52:13.323] INFO: test: HighRate no parameter change
[10:52:13.323] INFO: running: highrate
[10:52:13.324] INFO: ----------------------------------------------------------------------
[10:52:13.324] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:52:13.324] INFO: ----------------------------------------------------------------------
[10:52:13.935] INFO: Expecting 208000 events.
[10:52:25.873] INFO: 208000 events read in total (11411ms).
[10:52:25.876] INFO: Test took 12542ms.
[10:52:26.023] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:26.276] INFO: number of dead pixels (per ROC): 0 0 0 3 0 0 0 0 0 0 0 0 0 0 0 0
[10:52:26.276] INFO: number of red-efficiency pixels: 84 47 89 127 153 163 138 119 101 148 130 94 112 66 22 39
[10:52:26.276] INFO: number of X-ray hits detected: 65734 44273 68613 111359 112326 116783 113831 81042 85021 104750 99184 86998 89391 53906 25021 28809
[10:52:26.276] 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:52:26.276] INFO: number of Vcal hits detected: 207913 207952 207907 207723 207844 207831 207860 207878 207898 207845 207865 207904 207886 207932 207978 207960
[10:52:26.276] 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 100.0 100.0 100.0 100.0 100.0
[10:52:26.276] 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 99.9 100.0 100.0 100.0
[10:52:26.276] INFO: X-ray hit rate [MHz/cm2]: 19.3 13.0 20.1 32.6 32.9 34.2 33.4 23.8 24.9 30.7 29.1 25.5 26.2 15.8 7.3 8.4
[10:52:26.276] INFO: PixTestHighRate::doXPixelAlive() done
[10:52:26.324] INFO: PixTest:: pg_setup set to default.
[10:52:26.337] INFO: enter test to run
[10:52:38.811] INFO: test: HighRate no parameter change
[10:52:38.811] INFO: running: highrate
[10:52:38.812] INFO: ----------------------------------------------------------------------
[10:52:38.812] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:52:38.812] INFO: ----------------------------------------------------------------------
[10:52:39.422] INFO: Expecting 208000 events.
[10:52:53.094] INFO: 208000 events read in total (13145ms).
[10:52:53.100] INFO: Test took 14280ms.
[10:52:53.404] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:53.704] INFO: number of dead pixels (per ROC): 0 0 0 3 0 0 0 0 0 0 0 0 0 0 0 0
[10:52:53.704] INFO: number of red-efficiency pixels: 242 121 224 371 551 568 446 296 302 358 362 338 303 150 75 49
[10:52:53.704] INFO: number of X-ray hits detected: 134403 90703 140137 227708 229794 239663 233321 166175 174445 214219 202345 177041 184262 110146 50965 59672
[10:52:53.704] 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:52:53.704] INFO: number of Vcal hits detected: 207730 207876 207754 207441 207399 207359 207511 207686 207686 207614 207616 207632 207683 207841 207924 207951
[10:52:53.704] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.8 99.9 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[10:52:53.704] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.7 99.7 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[10:52:53.704] INFO: X-ray hit rate [MHz/cm2]: 39.4 26.6 41.1 66.7 67.4 70.2 68.4 48.7 51.1 62.8 59.3 51.9 54.0 32.3 14.9 17.5
[10:52:53.704] INFO: PixTestHighRate::doXPixelAlive() done
[10:52:53.751] INFO: PixTest:: pg_setup set to default.
[10:52:53.768] INFO: enter test to run
[10:53:04.810] INFO: test: HighRate no parameter change
[10:53:04.811] INFO: running: highrate
[10:53:04.812] INFO: ----------------------------------------------------------------------
[10:53:04.812] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:53:04.812] INFO: ----------------------------------------------------------------------
[10:53:05.424] INFO: Expecting 208000 events.
[10:53:20.941] INFO: 208000 events read in total (14990ms).
[10:53:20.948] INFO: Test took 16128ms.
[10:53:21.386] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:21.735] INFO: number of dead pixels (per ROC): 0 0 0 3 0 0 0 0 0 0 0 0 0 0 0 0
[10:53:21.735] INFO: number of red-efficiency pixels: 459 226 514 875 1203 1301 1083 734 657 838 917 720 731 324 124 117
[10:53:21.735] INFO: number of X-ray hits detected: 201057 135724 209244 338290 341852 356005 345521 247391 259479 317682 301391 263845 274548 165633 76506 88726
[10:53:21.735] 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:53:21.735] INFO: number of Vcal hits detected: 207442 207758 207367 206804 206443 206279 206623 207083 207237 207020 206898 207172 207165 207647 207869 207882
[10:53:21.735] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.3 99.3 99.4 99.6 99.7 99.6 99.5 99.6 99.6 99.8 99.9 99.9
[10:53:21.735] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.7 99.4 99.3 99.2 99.3 99.6 99.6 99.5 99.5 99.6 99.6 99.8 99.9 99.9
[10:53:21.735] INFO: X-ray hit rate [MHz/cm2]: 58.9 39.8 61.3 99.2 100.2 104.3 101.3 72.5 76.1 93.1 88.3 77.3 80.5 48.5 22.4 26.0
[10:53:21.735] INFO: PixTestHighRate::doXPixelAlive() done
[10:53:21.783] INFO: PixTest:: pg_setup set to default.
[10:53:21.801] INFO: enter test to run
[10:53:41.306] INFO: test: exit no parameter change
[10:53:41.612] QUIET: Connection to board 32 closed.
[10:53:41.613] INFO: pXar: this is the end, my friend