[15:11:15.699] INFO: *** Welcome to pxar ***
[15:11:15.699] INFO: *** Today: 2016/06/24
[15:11:15.769] INFO: *** Version: v1.9.0-814-g7497
[15:11:15.769] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//dacParameters35_C15.dat
[15:11:15.770] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:11:15.770] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//defaultMaskFile.dat
[15:11:15.770] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C15.dat
[15:11:15.845] INFO: clk: 4
[15:11:15.845] INFO: ctr: 4
[15:11:15.845] INFO: sda: 19
[15:11:15.845] INFO: tin: 9
[15:11:15.845] INFO: level: 15
[15:11:15.845] INFO: triggerdelay: 0
[15:11:15.845] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[15:11:15.845] INFO: Log level: INFO
[15:11:15.862] QUIET: Connection to board DTB_WREKRL opened.
[15:11:15.866] 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:
------------------------------------------------------
[15:11:15.869] INFO: RPC call hashes of host and DTB match: 398089610
[15:11:17.399] INFO: DUT info:
[15:11:17.399] INFO: The DUT currently contains the following objects:
[15:11:17.399] INFO: 2 TBM Cores tbm08c (2 ON)
[15:11:17.399] INFO: TBM Core alpha (0): 7 registers set
[15:11:17.399] INFO: TBM Core beta (1): 7 registers set
[15:11:17.399] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:11:17.399] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.399] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.400] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.400] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.400] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.400] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.400] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.400] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.400] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.400] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.400] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.400] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.400] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.400] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.400] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.400] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:17.803] INFO: enter 'restricted' command line mode
[15:11:17.803] INFO: enter test to run
[15:11:27.003] INFO: test: PixelAlive no parameter change
[15:11:27.004] INFO: running: pixelalive
[15:11:27.013] INFO: ----------------------------------------------------------------------
[15:11:27.013] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:11:27.013] INFO: ----------------------------------------------------------------------
[15:11:27.330] INFO: Expecting 41600 events.
[15:11:31.661] INFO: 41600 events read in total (3613ms).
[15:11:31.831] INFO: Test took 4816ms.
[15:11:31.841] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:32.103] INFO: PixTestAlive::aliveTest() done
[15:11:32.103] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:11:32.134] INFO: enter test to run
[15:13:34.171] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[15:13:34.171] INFO: running: highrate
[15:13:34.171] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[15:13:34.329] INFO: ----------------------------------------------------------------------
[15:13:34.329] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[15:13:34.329] INFO: ----------------------------------------------------------------------
[15:13:34.329] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[15:13:34.329] INFO: edge/corner pixel THR is adjusted
[15:13:34.329] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:13:35.288] INFO: Collecting data for 5 seconds...
[15:13:40.304] INFO: Done with hot pixel readout
[15:13:51.834] INFO: PixTest:: pg_setup set to default.
[15:13:51.835] INFO: 0 hot pixels found in step 0
[15:13:51.847] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:13:51.851] INFO: PixTest::trimHotPixels() done
[15:13:51.871] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C0.dat
[15:13:51.876] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C1.dat
[15:13:51.882] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C2.dat
[15:13:51.887] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C3.dat
[15:13:51.892] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C4.dat
[15:13:51.899] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C5.dat
[15:13:51.904] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C6.dat
[15:13:51.909] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C7.dat
[15:13:51.914] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C8.dat
[15:13:51.919] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C9.dat
[15:13:51.924] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C10.dat
[15:13:51.930] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C11.dat
[15:13:51.935] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C12.dat
[15:13:51.940] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C13.dat
[15:13:51.945] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C14.dat
[15:13:51.950] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C15.dat
[15:13:51.955] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//defaultMaskFile.dat
[15:13:51.966] INFO: enter test to run
[15:14:13.250] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[15:14:13.250] INFO: running: highrate
[15:14:13.255] INFO: ----------------------------------------------------------------------
[15:14:13.255] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:14:13.255] INFO: ----------------------------------------------------------------------
[15:14:13.255] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:14:13.255] INFO: edge/corner pixel THR is adjusted
[15:14:13.255] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:14:14.214] INFO: Collecting data for 1 seconds...
[15:14:15.218] INFO: Done with hot pixel readout
[15:14:19.138] INFO: PixTest:: pg_setup set to default.
[15:14:19.139] INFO: 0 hot pixels found in step 0
[15:14:19.144] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:14:19.244] INFO: PixTest::trimHotPixels() done
[15:14:19.244] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C0.dat
[15:14:19.251] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C1.dat
[15:14:19.257] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C2.dat
[15:14:19.262] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C3.dat
[15:14:19.267] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C4.dat
[15:14:19.272] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C5.dat
[15:14:19.277] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C6.dat
[15:14:19.282] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C7.dat
[15:14:19.288] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C8.dat
[15:14:19.293] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C9.dat
[15:14:19.298] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C10.dat
[15:14:19.303] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C11.dat
[15:14:19.308] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C12.dat
[15:14:19.313] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C13.dat
[15:14:19.319] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C14.dat
[15:14:19.324] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C15.dat
[15:14:19.329] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//defaultMaskFile.dat
[15:14:19.338] INFO: enter test to run
[15:14:41.778] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[15:14:41.778] INFO: running: xray
[15:14:41.780] INFO: ----------------------------------------------------------------------
[15:14:41.780] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:14:41.780] INFO: ----------------------------------------------------------------------
[15:14:42.744] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:14:54.431] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:15:23.736] INFO: Resuming triggers.
[15:15:35.433] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:16:04.689] INFO: Resuming triggers.
[15:16:16.376] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:16:46.045] INFO: Resuming triggers.
[15:16:57.738] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:17:27.468] INFO: Resuming triggers.
[15:17:39.162] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[15:18:09.167] INFO: Resuming triggers.
[15:18:20.860] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:18:50.472] INFO: Resuming triggers.
[15:19:02.162] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[15:19:31.731] INFO: Resuming triggers.
[15:19:43.415] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[15:20:12.674] INFO: Resuming triggers.
[15:20:19.493] INFO: data taking finished, elapsed time: 100 seconds.
[15:20:36.771] INFO: PixTest:: pg_setup set to default.
[15:20:36.774] INFO: PixTestXray::doPhRun() done
[15:20:36.908] INFO: enter test to run
[15:21:24.968] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:21:24.968] INFO: running: xray
[15:21:24.969] INFO: ----------------------------------------------------------------------
[15:21:24.969] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:21:24.969] INFO: ----------------------------------------------------------------------
[15:21:25.933] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:21:32.672] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:22:02.733] INFO: Resuming triggers.
[15:22:09.480] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[15:22:39.673] INFO: Resuming triggers.
[15:22:46.411] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[15:23:16.652] INFO: Resuming triggers.
[15:23:23.389] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[15:23:53.447] INFO: Resuming triggers.
[15:24:00.191] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[15:24:30.289] INFO: Resuming triggers.
[15:24:37.040] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[15:25:07.237] INFO: Resuming triggers.
[15:25:13.978] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[15:25:44.138] INFO: Resuming triggers.
[15:25:50.875] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[15:26:21.007] INFO: Resuming triggers.
[15:26:27.748] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[15:26:57.866] INFO: Resuming triggers.
[15:27:04.620] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[15:27:35.669] INFO: Resuming triggers.
[15:27:42.420] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[15:28:13.339] INFO: Resuming triggers.
[15:28:20.078] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[15:28:50.855] INFO: Resuming triggers.
[15:28:57.596] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[15:29:29.353] INFO: Resuming triggers.
[15:29:36.103] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[15:30:07.442] INFO: Resuming triggers.
[15:30:13.364] INFO: data taking finished, elapsed time: 100 seconds.
[15:30:41.188] INFO: PixTest:: pg_setup set to default.
[15:30:41.191] INFO: PixTestXray::doPhRun() done
[15:30:41.340] INFO: enter test to run
[15:30:54.956] INFO: test: HighRate no parameter change
[15:30:54.956] INFO: running: highrate
[15:30:54.957] INFO: ----------------------------------------------------------------------
[15:30:54.957] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:30:54.957] INFO: ----------------------------------------------------------------------
[15:30:55.097] INFO: Expecting 768 events.
[15:30:56.230] INFO: 768 events read in total (417ms).
[15:30:56.231] INFO: Test took 1268ms.
[15:30:57.034] INFO: Expecting 41600 events.
[15:31:00.127] INFO: 41600 events read in total (2566ms).
[15:31:00.128] INFO: Test took 3889ms.
[15:31:00.161] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:00.885] INFO: Expecting 41600 events.
[15:31:04.079] INFO: 41600 events read in total (2667ms).
[15:31:04.079] INFO: Test took 3899ms.
[15:31:04.114] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:04.838] INFO: Expecting 41600 events.
[15:31:08.066] INFO: 41600 events read in total (2701ms).
[15:31:08.067] INFO: Test took 3934ms.
[15:31:08.102] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:08.826] INFO: Expecting 41600 events.
[15:31:12.063] INFO: 41600 events read in total (2710ms).
[15:31:12.064] INFO: Test took 3943ms.
[15:31:12.098] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:12.823] INFO: Expecting 41600 events.
[15:31:16.058] INFO: 41600 events read in total (2708ms).
[15:31:16.059] INFO: Test took 3942ms.
[15:31:16.094] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:16.818] INFO: Expecting 41600 events.
[15:31:20.052] INFO: 41600 events read in total (2707ms).
[15:31:20.053] INFO: Test took 3940ms.
[15:31:20.089] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:20.810] INFO: Expecting 41600 events.
[15:31:24.059] INFO: 41600 events read in total (2722ms).
[15:31:24.060] INFO: Test took 3953ms.
[15:31:24.094] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:24.817] INFO: Expecting 41600 events.
[15:31:28.057] INFO: 41600 events read in total (2714ms).
[15:31:28.058] INFO: Test took 3946ms.
[15:31:28.093] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:28.817] INFO: Expecting 41600 events.
[15:31:32.053] INFO: 41600 events read in total (2709ms).
[15:31:32.054] INFO: Test took 3943ms.
[15:31:32.089] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:32.814] INFO: Expecting 41600 events.
[15:31:36.063] INFO: 41600 events read in total (2722ms).
[15:31:36.064] INFO: Test took 3956ms.
[15:31:36.099] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:36.823] INFO: Expecting 41600 events.
[15:31:40.068] INFO: 41600 events read in total (2719ms).
[15:31:40.069] INFO: Test took 3953ms.
[15:31:40.103] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:40.824] INFO: Expecting 41600 events.
[15:31:44.062] INFO: 41600 events read in total (2711ms).
[15:31:44.063] INFO: Test took 3941ms.
[15:31:44.097] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:44.818] INFO: Expecting 41600 events.
[15:31:48.051] INFO: 41600 events read in total (2706ms).
[15:31:48.052] INFO: Test took 3935ms.
[15:31:48.086] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:48.812] INFO: Expecting 41600 events.
[15:31:52.065] INFO: 41600 events read in total (2726ms).
[15:31:52.066] INFO: Test took 3962ms.
[15:31:52.101] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:52.821] INFO: Expecting 41600 events.
[15:31:56.074] INFO: 41600 events read in total (2726ms).
[15:31:56.075] INFO: Test took 3955ms.
[15:31:56.110] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:56.830] INFO: Expecting 41600 events.
[15:32:00.062] INFO: 41600 events read in total (2706ms).
[15:32:00.064] INFO: Test took 3937ms.
[15:32:00.098] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:00.823] INFO: Expecting 41600 events.
[15:32:04.078] INFO: 41600 events read in total (2728ms).
[15:32:04.079] INFO: Test took 3963ms.
[15:32:04.114] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:04.836] INFO: Expecting 41600 events.
[15:32:08.073] INFO: 41600 events read in total (2711ms).
[15:32:08.074] INFO: Test took 3942ms.
[15:32:08.109] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:08.828] INFO: Expecting 41600 events.
[15:32:12.046] INFO: 41600 events read in total (2691ms).
[15:32:12.047] INFO: Test took 3920ms.
[15:32:12.081] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:12.803] INFO: Expecting 41600 events.
[15:32:15.897] INFO: 41600 events read in total (2567ms).
[15:32:15.898] INFO: Test took 3799ms.
[15:32:15.931] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:16.296] INFO: enter test to run
[15:32:26.091] INFO: test: HighRate no parameter change
[15:32:26.091] INFO: running: highrate
[15:32:26.092] INFO: ----------------------------------------------------------------------
[15:32:26.092] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:32:26.092] INFO: ----------------------------------------------------------------------
[15:32:26.707] INFO: Expecting 208000 events.
[15:32:38.612] INFO: 208000 events read in total (11378ms).
[15:32:38.615] INFO: Test took 12516ms.
[15:32:38.763] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:39.016] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:32:39.016] INFO: number of red-efficiency pixels: 67 39 89 132 130 130 124 103 90 167 110 110 111 55 29 23
[15:32:39.016] INFO: number of X-ray hits detected: 57802 38115 64985 102813 110885 114421 115385 81738 79335 111856 106362 89057 97304 56843 22463 24443
[15:32:39.016] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:32:39.016] INFO: number of Vcal hits detected: 207931 207961 207911 207867 207865 207868 207874 207895 207909 207829 207889 207887 207887 207942 207969 207977
[15:32:39.016] 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 99.9 100.0 100.0 100.0
[15:32:39.016] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[15:32:39.016] INFO: X-ray hit rate [MHz/cm2]: 16.9 11.2 19.0 30.1 32.5 33.5 33.8 24.0 23.3 32.8 31.2 26.1 28.5 16.7 6.6 7.2
[15:32:39.016] INFO: PixTestHighRate::doXPixelAlive() done
[15:32:39.062] INFO: PixTest:: pg_setup set to default.
[15:32:39.076] INFO: enter test to run
[15:32:59.435] INFO: test: HighRate no parameter change
[15:32:59.435] INFO: running: highrate
[15:32:59.436] INFO: ----------------------------------------------------------------------
[15:32:59.436] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:32:59.436] INFO: ----------------------------------------------------------------------
[15:33:00.053] INFO: Expecting 208000 events.
[15:33:13.504] INFO: 208000 events read in total (12924ms).
[15:33:13.509] INFO: Test took 14063ms.
[15:33:13.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:14.081] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:33:14.081] INFO: number of red-efficiency pixels: 154 99 256 431 359 350 335 303 230 451 294 334 280 192 51 46
[15:33:14.081] INFO: number of X-ray hits detected: 111896 73605 126115 200219 214948 220765 224531 158445 154211 215925 206172 173410 189530 109939 43575 47658
[15:33:14.081] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:33:14.081] INFO: number of Vcal hits detected: 207838 207900 207729 207531 207616 207629 207649 207671 207756 207515 207690 207642 207706 207795 207948 207953
[15:33:14.081] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.9 99.8 99.9 99.9 100.0 100.0
[15:33:14.081] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.9 99.8 99.9 99.9 100.0 100.0
[15:33:14.081] INFO: X-ray hit rate [MHz/cm2]: 32.8 21.6 37.0 58.7 63.0 64.7 65.8 46.4 45.2 63.3 60.4 50.8 55.6 32.2 12.8 14.0
[15:33:14.081] INFO: PixTestHighRate::doXPixelAlive() done
[15:33:14.131] INFO: PixTest:: pg_setup set to default.
[15:33:14.143] INFO: enter test to run
[15:33:30.747] INFO: test: HighRate no parameter change
[15:33:30.747] INFO: running: highrate
[15:33:30.748] INFO: ----------------------------------------------------------------------
[15:33:30.748] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:33:30.749] INFO: ----------------------------------------------------------------------
[15:33:31.363] INFO: Expecting 208000 events.
[15:33:46.630] INFO: 208000 events read in total (14740ms).
[15:33:46.637] INFO: Test took 15880ms.
[15:33:47.067] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:47.412] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:33:47.412] INFO: number of red-efficiency pixels: 347 173 530 925 764 781 888 656 507 1040 715 739 652 448 92 84
[15:33:47.412] INFO: number of X-ray hits detected: 169618 112104 190593 301549 325948 335232 339572 240109 232108 325352 311160 262255 287082 167173 65852 72504
[15:33:47.412] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:33:47.412] INFO: number of Vcal hits detected: 207607 207816 207337 206915 207086 207095 206938 207180 207433 206706 207182 207122 207270 207484 207900 207916
[15:33:47.412] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.6 99.6 99.5 99.7 99.8 99.4 99.6 99.6 99.7 99.8 100.0 100.0
[15:33:47.412] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.5 99.6 99.6 99.5 99.6 99.7 99.4 99.6 99.6 99.6 99.8 100.0 100.0
[15:33:47.412] INFO: X-ray hit rate [MHz/cm2]: 49.7 32.9 55.9 88.4 95.5 98.3 99.5 70.4 68.0 95.4 91.2 76.9 84.1 49.0 19.3 21.3
[15:33:47.412] INFO: PixTestHighRate::doXPixelAlive() done
[15:33:47.466] INFO: PixTest:: pg_setup set to default.
[15:33:47.486] INFO: enter test to run
[15:33:51.114] INFO: test: exit no parameter change
[15:33:51.385] QUIET: Connection to board 33 closed.
[15:33:51.401] INFO: pXar: this is the end, my friend