[10:00:10.189] INFO: *** Welcome to pxar ***
[10:00:10.189] INFO: *** Today: 2016/04/28
[10:00:10.209] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:00:10.209] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//dacParameters35_C15.dat
[10:00:10.238] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:00:10.238] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//defaultMaskFile.dat
[10:00:10.242] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C15.dat
[10:00:10.377] INFO: clk: 4
[10:00:10.377] INFO: ctr: 4
[10:00:10.377] INFO: sda: 19
[10:00:10.377] INFO: tin: 9
[10:00:10.377] INFO: level: 15
[10:00:10.377] INFO: triggerdelay: 0
[10:00:10.377] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:00:10.377] INFO: Log level: INFO
[10:00:10.394] QUIET: Connection to board DTB_WREKRL opened.
[10:00:10.398] 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:
------------------------------------------------------
[10:00:10.400] INFO: RPC call hashes of host and DTB match: 398089610
[10:00:11.927] INFO: DUT info:
[10:00:11.927] INFO: The DUT currently contains the following objects:
[10:00:11.927] INFO: 2 TBM Cores tbm08c (2 ON)
[10:00:11.927] INFO: TBM Core alpha (0): 7 registers set
[10:00:11.927] INFO: TBM Core beta (1): 7 registers set
[10:00:11.927] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:00:11.927] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:11.927] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:11.927] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:11.927] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:11.927] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:11.927] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:11.927] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:11.927] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:11.927] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:11.927] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:11.927] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:11.927] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:11.927] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:11.927] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:11.927] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:11.927] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:12.330] INFO: enter 'restricted' command line mode
[10:00:12.330] INFO: enter test to run
[10:00:19.293] INFO: test: PixelAlive no parameter change
[10:00:19.293] INFO: running: pixelalive
[10:00:19.300] INFO: ----------------------------------------------------------------------
[10:00:19.300] 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:00:19.300] INFO: ----------------------------------------------------------------------
[10:00:19.618] INFO: Expecting 41600 events.
[10:00:23.673] INFO: 41600 events read in total (3337ms).
[10:00:23.843] INFO: Test took 4541ms.
[10:00:23.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:24.116] INFO: PixTestAlive::aliveTest() done
[10:00:24.116] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 17 0
[10:00:24.144] INFO: enter test to run
[10:01:10.949] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:01:10.949] INFO: running: highrate
[10:01:10.950] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:01:11.239] INFO: ----------------------------------------------------------------------
[10:01:11.239] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:01:11.239] INFO: ----------------------------------------------------------------------
[10:01:11.239] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:01:11.239] INFO: edge/corner pixel THR is adjusted
[10:01:11.239] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:01:12.198] INFO: Collecting data for 5 seconds...
[10:01:17.215] INFO: Done with hot pixel readout
[10:01:28.468] INFO: PixTest:: pg_setup set to default.
[10:01:28.469] INFO: 1 hot pixels found in step 0
[10:01:29.486] INFO: Collecting data for 5 seconds...
[10:01:34.502] INFO: Done with hot pixel readout
[10:01:45.377] INFO: PixTest:: pg_setup set to default.
[10:01:45.378] INFO: 0 hot pixels found in step 1
[10:01:45.413] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:01:45.417] INFO: PixTest::trimHotPixels() done
[10:01:45.417] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C0.dat
[10:01:45.423] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C1.dat
[10:01:45.429] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C2.dat
[10:01:45.435] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C3.dat
[10:01:45.441] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C4.dat
[10:01:45.446] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C5.dat
[10:01:45.451] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C6.dat
[10:01:45.457] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C7.dat
[10:01:45.462] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C8.dat
[10:01:45.467] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C9.dat
[10:01:45.472] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C10.dat
[10:01:45.478] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C11.dat
[10:01:45.483] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C12.dat
[10:01:45.488] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C13.dat
[10:01:45.493] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C14.dat
[10:01:45.499] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C15.dat
[10:01:45.504] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//defaultMaskFile.dat
[10:01:45.514] INFO: enter test to run
[10:02:38.917] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:02:38.917] INFO: running: highrate
[10:02:38.921] INFO: ----------------------------------------------------------------------
[10:02:38.921] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:02:38.921] INFO: ----------------------------------------------------------------------
[10:02:38.921] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:02:38.921] INFO: edge/corner pixel THR is adjusted
[10:02:38.921] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:02:39.878] INFO: Collecting data for 1 seconds...
[10:02:40.882] INFO: Done with hot pixel readout
[10:02:44.906] INFO: PixTest:: pg_setup set to default.
[10:02:44.907] INFO: 0 hot pixels found in step 0
[10:02:44.913] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:02:44.990] INFO: PixTest::trimHotPixels() done
[10:02:44.991] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C0.dat
[10:02:44.002] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C1.dat
[10:02:45.010] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C2.dat
[10:02:45.016] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C3.dat
[10:02:45.021] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C4.dat
[10:02:45.026] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C5.dat
[10:02:45.032] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C6.dat
[10:02:45.037] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C7.dat
[10:02:45.043] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C8.dat
[10:02:45.048] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C9.dat
[10:02:45.053] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C10.dat
[10:02:45.058] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C11.dat
[10:02:45.064] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C12.dat
[10:02:45.070] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C13.dat
[10:02:45.075] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C14.dat
[10:02:45.080] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C15.dat
[10:02:45.086] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-1-15_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//defaultMaskFile.dat
[10:02:45.097] INFO: enter test to run
[10:04:47.132] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:04:47.132] INFO: running: xray
[10:04:47.133] INFO: ----------------------------------------------------------------------
[10:04:47.133] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:04:47.133] INFO: ----------------------------------------------------------------------
[10:04:48.096] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:04:59.767] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:05:29.015] INFO: Resuming triggers.
[10:05:40.688] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:06:09.847] INFO: Resuming triggers.
[10:06:21.520] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:06:50.584] INFO: Resuming triggers.
[10:07:02.264] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:07:31.628] INFO: Resuming triggers.
[10:07:43.305] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[10:08:12.585] INFO: Resuming triggers.
[10:08:24.260] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:08:53.239] INFO: Resuming triggers.
[10:09:04.916] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[10:09:32.521] INFO: Resuming triggers.
[10:09:44.191] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[10:10:12.146] INFO: Resuming triggers.
[10:10:19.088] INFO: data taking finished, elapsed time: 100 seconds.
[10:10:35.267] INFO: PixTest:: pg_setup set to default.
[10:10:35.271] INFO: PixTestXray::doPhRun() done
[10:10:35.966] INFO: enter test to run
[10:12:45.200] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:12:45.200] INFO: running: xray
[10:12:45.276] INFO: ----------------------------------------------------------------------
[10:12:45.277] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:12:45.277] INFO: ----------------------------------------------------------------------
[10:12:46.305] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:12:52.892] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:13:23.021] INFO: Resuming triggers.
[10:13:29.609] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:13:59.757] INFO: Resuming triggers.
[10:14:06.344] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[10:14:36.442] INFO: Resuming triggers.
[10:14:43.030] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[10:15:13.133] INFO: Resuming triggers.
[10:15:19.721] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[10:15:49.806] INFO: Resuming triggers.
[10:15:56.398] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[10:16:26.458] INFO: Resuming triggers.
[10:16:33.049] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:17:03.164] INFO: Resuming triggers.
[10:17:09.756] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[10:17:39.851] INFO: Resuming triggers.
[10:17:46.442] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[10:18:16.444] INFO: Resuming triggers.
[10:18:23.032] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[10:18:53.057] INFO: Resuming triggers.
[10:18:59.648] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[10:19:29.765] INFO: Resuming triggers.
[10:19:36.353] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[10:20:06.536] INFO: Resuming triggers.
[10:20:13.126] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[10:20:43.231] INFO: Resuming triggers.
[10:20:49.818] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[10:21:18.085] INFO: Resuming triggers.
[10:21:24.671] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[10:21:53.150] INFO: Resuming triggers.
[10:21:54.643] INFO: data taking finished, elapsed time: 100 seconds.
[10:22:01.813] INFO: PixTest:: pg_setup set to default.
[10:22:01.817] INFO: PixTestXray::doPhRun() done
[10:22:01.969] INFO: enter test to run
[10:22:16.948] INFO: test: HighRate no parameter change
[10:22:16.948] INFO: running: highrate
[10:22:16.949] INFO: ----------------------------------------------------------------------
[10:22:16.949] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:22:16.949] INFO: ----------------------------------------------------------------------
[10:22:17.090] INFO: Expecting 768 events.
[10:22:18.246] INFO: 768 events read in total (440ms).
[10:22:18.247] INFO: Test took 1291ms.
[10:22:19.050] INFO: Expecting 41600 events.
[10:22:22.203] INFO: 41600 events read in total (2626ms).
[10:22:22.204] INFO: Test took 3941ms.
[10:22:22.239] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:22.966] INFO: Expecting 41600 events.
[10:22:26.200] INFO: 41600 events read in total (2707ms).
[10:22:26.201] INFO: Test took 3944ms.
[10:22:26.236] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:26.960] INFO: Expecting 41600 events.
[10:22:30.208] INFO: 41600 events read in total (2722ms).
[10:22:30.209] INFO: Test took 3954ms.
[10:22:30.243] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:30.968] INFO: Expecting 41600 events.
[10:22:34.214] INFO: 41600 events read in total (2719ms).
[10:22:34.215] INFO: Test took 3955ms.
[10:22:34.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:34.970] INFO: Expecting 41600 events.
[10:22:38.214] INFO: 41600 events read in total (2717ms).
[10:22:38.215] INFO: Test took 3947ms.
[10:22:38.250] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:38.973] INFO: Expecting 41600 events.
[10:22:42.230] INFO: 41600 events read in total (2730ms).
[10:22:42.231] INFO: Test took 3963ms.
[10:22:42.265] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:42.985] INFO: Expecting 41600 events.
[10:22:46.239] INFO: 41600 events read in total (2727ms).
[10:22:46.240] INFO: Test took 3957ms.
[10:22:46.275] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:46.998] INFO: Expecting 41600 events.
[10:22:50.247] INFO: 41600 events read in total (2722ms).
[10:22:50.248] INFO: Test took 3954ms.
[10:22:50.282] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:50.005] INFO: Expecting 41600 events.
[10:22:54.268] INFO: 41600 events read in total (2736ms).
[10:22:54.269] INFO: Test took 3970ms.
[10:22:54.302] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:55.029] INFO: Expecting 41600 events.
[10:22:58.298] INFO: 41600 events read in total (2742ms).
[10:22:58.298] INFO: Test took 3978ms.
[10:22:58.332] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:59.053] INFO: Expecting 41600 events.
[10:23:02.297] INFO: 41600 events read in total (2717ms).
[10:23:02.298] INFO: Test took 3948ms.
[10:23:02.332] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:03.056] INFO: Expecting 41600 events.
[10:23:06.318] INFO: 41600 events read in total (2735ms).
[10:23:06.319] INFO: Test took 3968ms.
[10:23:06.353] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:07.078] INFO: Expecting 41600 events.
[10:23:10.338] INFO: 41600 events read in total (2733ms).
[10:23:10.339] INFO: Test took 3968ms.
[10:23:10.372] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:11.098] INFO: Expecting 41600 events.
[10:23:14.344] INFO: 41600 events read in total (2720ms).
[10:23:14.345] INFO: Test took 3954ms.
[10:23:14.379] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:15.101] INFO: Expecting 41600 events.
[10:23:18.363] INFO: 41600 events read in total (2735ms).
[10:23:18.364] INFO: Test took 3967ms.
[10:23:18.398] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:19.120] INFO: Expecting 41600 events.
[10:23:22.381] INFO: 41600 events read in total (2735ms).
[10:23:22.382] INFO: Test took 3965ms.
[10:23:22.416] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:23.137] INFO: Expecting 41600 events.
[10:23:26.390] INFO: 41600 events read in total (2726ms).
[10:23:26.391] INFO: Test took 3957ms.
[10:23:26.425] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:27.147] INFO: Expecting 41600 events.
[10:23:30.386] INFO: 41600 events read in total (2712ms).
[10:23:30.387] INFO: Test took 3945ms.
[10:23:30.421] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:31.143] INFO: Expecting 41600 events.
[10:23:34.398] INFO: 41600 events read in total (2729ms).
[10:23:34.399] INFO: Test took 3961ms.
[10:23:34.433] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:35.159] INFO: Expecting 41600 events.
[10:23:38.247] INFO: 41600 events read in total (2561ms).
[10:23:38.248] INFO: Test took 3796ms.
[10:23:38.281] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:38.644] INFO: enter test to run
[10:23:48.363] INFO: test: \HighRate no parameter change
[10:23:48.364] INFO: running: \highrate
[10:23:48.377] INFO: command ->\highrate<- not known, ignored
[10:23:48.377] INFO: enter test to run
[10:23:59.219] INFO: test: HighRate no parameter change
[10:23:59.219] INFO: running: highrate
[10:23:59.220] INFO: ----------------------------------------------------------------------
[10:23:59.220] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:23:59.220] INFO: ----------------------------------------------------------------------
[10:23:59.851] INFO: Expecting 208000 events.
[10:24:11.730] INFO: 208000 events read in total (11352ms).
[10:24:11.735] INFO: Test took 12495ms.
[10:24:11.884] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:12.137] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 17 0
[10:24:12.137] INFO: number of red-efficiency pixels: 83 60 110 144 114 138 148 127 93 117 121 100 123 53 44 21
[10:24:12.137] INFO: number of X-ray hits detected: 62857 43395 66831 107547 113276 118218 117613 82800 80618 95615 96549 86449 91139 53492 21583 26057
[10:24:12.137] 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:24:12.137] INFO: number of Vcal hits detected: 207915 207940 207888 207853 207882 207861 207848 207869 207907 207883 207875 207898 207873 207947 207122 207979
[10:24:12.137] 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 99.9 100.0 100.0 100.0
[10:24:12.137] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 100.0 99.9 100.0 99.6 100.0
[10:24:12.137] INFO: X-ray hit rate [MHz/cm2]: 18.4 12.7 19.6 31.5 33.2 34.7 34.5 24.3 23.6 28.0 28.3 25.3 26.7 15.7 6.3 7.6
[10:24:12.137] INFO: PixTestHighRate::doXPixelAlive() done
[10:24:12.186] INFO: PixTest:: pg_setup set to default.
[10:24:12.200] INFO: enter test to run
[10:24:24.963] INFO: test: HighRate no parameter change
[10:24:24.963] INFO: running: highrate
[10:24:24.964] INFO: ----------------------------------------------------------------------
[10:24:24.964] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:24:24.964] INFO: ----------------------------------------------------------------------
[10:24:25.581] INFO: Expecting 208000 events.
[10:24:39.162] INFO: 208000 events read in total (13054ms).
[10:24:39.170] INFO: Test took 14198ms.
[10:24:39.473] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:39.774] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 17 0
[10:24:39.774] INFO: number of red-efficiency pixels: 218 117 290 443 367 392 410 339 293 356 394 267 290 167 70 58
[10:24:39.774] INFO: number of X-ray hits detected: 127459 87291 135180 217808 230220 238711 237391 165912 163097 192101 194187 173647 184072 107704 43491 52938
[10:24:39.774] 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:24:39.774] INFO: number of Vcal hits detected: 207769 207881 207675 207531 207604 207573 207547 207631 207691 207627 207588 207720 207700 207827 207095 207940
[10:24:39.774] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[10:24:39.774] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.9 99.9 99.9 99.6 100.0
[10:24:39.774] INFO: X-ray hit rate [MHz/cm2]: 37.4 25.6 39.6 63.8 67.5 70.0 69.6 48.6 47.8 56.3 56.9 50.9 54.0 31.6 12.7 15.5
[10:24:39.774] INFO: PixTestHighRate::doXPixelAlive() done
[10:24:39.826] INFO: PixTest:: pg_setup set to default.
[10:24:39.839] INFO: enter test to run
[10:24:50.275] INFO: test: HighRate no parameter change
[10:24:50.275] INFO: running: highrate
[10:24:50.276] INFO: ----------------------------------------------------------------------
[10:24:50.276] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:24:50.276] INFO: ----------------------------------------------------------------------
[10:24:50.891] INFO: Expecting 208000 events.
[10:25:06.543] INFO: 208000 events read in total (15125ms).
[10:25:06.550] INFO: Test took 16266ms.
[10:25:06.005] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:25:07.357] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 17 0
[10:25:07.357] INFO: number of red-efficiency pixels: 492 259 686 1123 883 970 992 753 681 841 924 576 652 333 124 120
[10:25:07.357] INFO: number of X-ray hits detected: 199980 137451 210816 339579 360715 373049 369558 261271 256419 301780 303498 272464 288035 169911 68112 83400
[10:25:07.357] 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:25:07.357] INFO: number of Vcal hits detected: 207432 207724 207083 206552 206941 206827 206764 207083 207221 207017 206912 207375 207266 207627 207039 207877
[10:25:07.357] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.6 99.4 99.5 99.5 99.5 99.6 99.7 99.6 99.5 99.7 99.7 99.8 99.9 99.9
[10:25:07.357] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.6 99.3 99.5 99.4 99.4 99.6 99.6 99.5 99.5 99.7 99.6 99.8 99.5 99.9
[10:25:07.357] INFO: X-ray hit rate [MHz/cm2]: 58.6 40.3 61.8 99.5 105.7 109.3 108.3 76.6 75.2 88.5 89.0 79.9 84.4 49.8 20.0 24.4
[10:25:07.357] INFO: PixTestHighRate::doXPixelAlive() done
[10:25:07.405] INFO: PixTest:: pg_setup set to default.
[10:25:07.423] INFO: enter test to run
[10:25:08.971] INFO: test: exit no parameter change
[10:25:09.439] QUIET: Connection to board 33 closed.
[10:25:09.516] INFO: pXar: this is the end, my friend