[15:17:34.194] INFO: *** Welcome to pxar ***
[15:17:34.194] INFO: *** Today: 2016/06/28
[15:17:34.215] INFO: *** Version: v1.9.0-814-g7497
[15:17:34.216] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//dacParameters35_C15.dat
[15:17:34.247] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:17:34.247] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//defaultMaskFile.dat
[15:17:34.247] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C15.dat
[15:17:34.335] INFO: clk: 4
[15:17:34.335] INFO: ctr: 4
[15:17:34.335] INFO: sda: 19
[15:17:34.335] INFO: tin: 9
[15:17:34.335] INFO: level: 15
[15:17:34.335] INFO: triggerdelay: 0
[15:17:34.336] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[15:17:34.336] INFO: Log level: INFO
[15:17:34.348] QUIET: Connection to board DTB_WREK4U opened.
[15:17:34.352] 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:
------------------------------------------------------
[15:17:34.354] INFO: RPC call hashes of host and DTB match: 398089610
[15:17:35.887] INFO: DUT info:
[15:17:35.887] INFO: The DUT currently contains the following objects:
[15:17:35.888] INFO: 2 TBM Cores tbm08c (2 ON)
[15:17:35.888] INFO: TBM Core alpha (0): 7 registers set
[15:17:35.888] INFO: TBM Core beta (1): 7 registers set
[15:17:35.888] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:17:35.888] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:35.888] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:35.888] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:35.888] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:35.888] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:35.888] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:35.888] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:35.888] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:35.888] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:35.888] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:35.888] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:35.888] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:35.888] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:35.889] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:35.889] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:35.889] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:36.292] INFO: enter 'restricted' command line mode
[15:17:36.292] INFO: enter test to run
[15:17:44.760] INFO: test: PixelAlive no parameter change
[15:17:44.760] INFO: running: pixelalive
[15:17:44.773] INFO: ----------------------------------------------------------------------
[15:17:44.773] 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:17:44.773] INFO: ----------------------------------------------------------------------
[15:17:45.100] INFO: Expecting 41600 events.
[15:17:49.477] INFO: 41600 events read in total (3658ms).
[15:17:49.643] INFO: Test took 4868ms.
[15:17:49.657] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:49.921] INFO: PixTestAlive::aliveTest() done
[15:17:49.921] INFO: number of dead pixels (per ROC): 17 1 1 0 0 0 0 0 0 0 0 0 0 0 0 2
[15:17:49.957] INFO: enter test to run
[15:18:21.304] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[15:18:21.304] INFO: running: highrate
[15:18:21.304] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[15:18:21.512] INFO: ----------------------------------------------------------------------
[15:18:21.512] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[15:18:21.512] INFO: ----------------------------------------------------------------------
[15:18:21.512] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[15:18:21.512] INFO: edge/corner pixel THR is adjusted
[15:18:21.512] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:18:22.470] INFO: Collecting data for 5 seconds...
[15:18:27.490] INFO: Done with hot pixel readout
[15:18:38.968] INFO: PixTest:: pg_setup set to default.
[15:18:38.969] INFO: 32 hot pixels found in step 0
[15:18:39.976] INFO: Collecting data for 5 seconds...
[15:18:44.996] INFO: Done with hot pixel readout
[15:18:55.993] INFO: PixTest:: pg_setup set to default.
[15:18:55.993] INFO: 3 hot pixels found in step 1
[15:18:56.977] INFO: Collecting data for 5 seconds...
[15:19:01.995] INFO: Done with hot pixel readout
[15:19:13.067] INFO: PixTest:: pg_setup set to default.
[15:19:13.068] INFO: 0 hot pixels found in step 2
[15:19:13.099] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:19:13.103] INFO: PixTest::trimHotPixels() done
[15:19:13.125] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C0.dat
[15:19:13.130] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C1.dat
[15:19:13.136] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C2.dat
[15:19:13.141] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C3.dat
[15:19:13.147] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C4.dat
[15:19:13.152] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C5.dat
[15:19:13.158] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C6.dat
[15:19:13.163] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C7.dat
[15:19:13.168] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C8.dat
[15:19:13.174] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C9.dat
[15:19:13.179] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C10.dat
[15:19:13.185] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C11.dat
[15:19:13.190] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C12.dat
[15:19:13.196] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C13.dat
[15:19:13.201] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C14.dat
[15:19:13.207] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C15.dat
[15:19:13.212] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//defaultMaskFile.dat
[15:19:13.238] INFO: enter test to run
[15:19:34.967] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[15:19:34.967] INFO: running: highrate
[15:19:34.972] INFO: ----------------------------------------------------------------------
[15:19:34.972] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:19:34.972] INFO: ----------------------------------------------------------------------
[15:19:34.972] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:19:34.972] INFO: edge/corner pixel THR is adjusted
[15:19:34.972] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:19:35.937] INFO: Collecting data for 1 seconds...
[15:19:36.942] INFO: Done with hot pixel readout
[15:19:40.766] INFO: PixTest:: pg_setup set to default.
[15:19:40.767] INFO: 0 hot pixels found in step 0
[15:19:40.773] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:19:40.863] INFO: PixTest::trimHotPixels() done
[15:19:40.863] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C0.dat
[15:19:40.869] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C1.dat
[15:19:40.875] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C2.dat
[15:19:40.881] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C3.dat
[15:19:40.886] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C4.dat
[15:19:40.891] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C5.dat
[15:19:40.897] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C6.dat
[15:19:40.902] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C7.dat
[15:19:40.908] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C8.dat
[15:19:40.913] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C9.dat
[15:19:40.918] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C10.dat
[15:19:40.924] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C11.dat
[15:19:40.929] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C12.dat
[15:19:40.935] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C13.dat
[15:19:40.940] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C14.dat
[15:19:40.945] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//trimParameters35_C15.dat
[15:19:40.995] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-2-47_FPIXTest-17C-FNAL-160623-1132_2016-06-23_11h32m_1466699572/000_FPIXTest_p17//defaultMaskFile.dat
[15:19:41.007] INFO: enter test to run
[15:20:02.255] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[15:20:02.255] INFO: running: xray
[15:20:02.256] INFO: ----------------------------------------------------------------------
[15:20:02.256] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:20:02.256] INFO: ----------------------------------------------------------------------
[15:20:03.219] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:20:14.000] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:20:45.086] INFO: Resuming triggers.
[15:20:56.868] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:21:26.802] INFO: Resuming triggers.
[15:21:38.584] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[15:22:08.037] INFO: Resuming triggers.
[15:22:19.818] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:22:49.241] INFO: Resuming triggers.
[15:23:01.024] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[15:23:30.498] INFO: Resuming triggers.
[15:23:42.279] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[15:24:11.768] INFO: Resuming triggers.
[15:24:23.548] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[15:24:53.087] INFO: Resuming triggers.
[15:25:04.863] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[15:25:34.435] INFO: Resuming triggers.
[15:25:40.541] INFO: data taking finished, elapsed time: 100 seconds.
[15:25:56.161] INFO: PixTest:: pg_setup set to default.
[15:25:56.165] INFO: PixTestXray::doPhRun() done
[15:25:56.303] INFO: enter test to run
[15:26:21.556] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:26:21.556] INFO: running: xray
[15:26:21.557] INFO: ----------------------------------------------------------------------
[15:26:21.558] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:26:21.558] INFO: ----------------------------------------------------------------------
[15:26:22.522] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:26:29.710] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:27:00.097] INFO: Resuming triggers.
[15:27:07.291] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[15:27:37.758] INFO: Resuming triggers.
[15:27:44.951] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[15:28:15.288] INFO: Resuming triggers.
[15:28:22.482] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[15:28:52.650] INFO: Resuming triggers.
[15:28:59.848] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[15:29:30.889] INFO: Resuming triggers.
[15:29:38.084] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[15:30:09.020] INFO: Resuming triggers.
[15:30:16.214] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[15:30:47.153] INFO: Resuming triggers.
[15:30:54.346] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[15:31:25.894] INFO: Resuming triggers.
[15:31:33.088] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[15:32:04.507] INFO: Resuming triggers.
[15:32:11.700] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[15:32:42.742] INFO: Resuming triggers.
[15:32:49.932] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[15:33:20.274] INFO: Resuming triggers.
[15:33:27.467] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[15:33:57.882] INFO: Resuming triggers.
[15:34:05.072] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[15:34:35.343] INFO: Resuming triggers.
[15:34:42.195] INFO: data taking finished, elapsed time: 100 seconds.
[15:35:11.332] INFO: PixTest:: pg_setup set to default.
[15:35:11.336] INFO: PixTestXray::doPhRun() done
[15:35:11.483] INFO: enter test to run
[15:35:36.193] INFO: test: HighRate no parameter change
[15:35:36.193] INFO: running: highrate
[15:35:36.210] INFO: ----------------------------------------------------------------------
[15:35:36.210] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:35:36.210] INFO: ----------------------------------------------------------------------
[15:35:36.366] INFO: Expecting 768 events.
[15:35:37.500] INFO: 768 events read in total (418ms).
[15:35:37.500] INFO: Test took 1269ms.
[15:35:38.303] INFO: Expecting 41600 events.
[15:35:41.368] INFO: 41600 events read in total (2538ms).
[15:35:41.369] INFO: Test took 3862ms.
[15:35:41.398] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:42.132] INFO: Expecting 41600 events.
[15:35:45.285] INFO: 41600 events read in total (2626ms).
[15:35:45.286] INFO: Test took 3871ms.
[15:35:45.316] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:46.049] INFO: Expecting 41600 events.
[15:35:49.232] INFO: 41600 events read in total (2657ms).
[15:35:49.233] INFO: Test took 3900ms.
[15:35:49.263] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:49.996] INFO: Expecting 41600 events.
[15:35:53.189] INFO: 41600 events read in total (2667ms).
[15:35:53.190] INFO: Test took 3910ms.
[15:35:53.220] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:53.950] INFO: Expecting 41600 events.
[15:35:57.150] INFO: 41600 events read in total (2673ms).
[15:35:57.151] INFO: Test took 3913ms.
[15:35:57.180] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:57.910] INFO: Expecting 41600 events.
[15:36:01.102] INFO: 41600 events read in total (2665ms).
[15:36:01.103] INFO: Test took 3907ms.
[15:36:01.133] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:01.868] INFO: Expecting 41600 events.
[15:36:05.051] INFO: 41600 events read in total (2657ms).
[15:36:05.052] INFO: Test took 3901ms.
[15:36:05.084] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:05.818] INFO: Expecting 41600 events.
[15:36:09.021] INFO: 41600 events read in total (2676ms).
[15:36:09.022] INFO: Test took 3921ms.
[15:36:09.051] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:09.785] INFO: Expecting 41600 events.
[15:36:12.978] INFO: 41600 events read in total (2666ms).
[15:36:12.979] INFO: Test took 3912ms.
[15:36:13.008] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:13.739] INFO: Expecting 41600 events.
[15:36:16.938] INFO: 41600 events read in total (2673ms).
[15:36:16.939] INFO: Test took 3915ms.
[15:36:16.969] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:17.698] INFO: Expecting 41600 events.
[15:36:20.891] INFO: 41600 events read in total (2666ms).
[15:36:20.892] INFO: Test took 3906ms.
[15:36:20.922] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:21.656] INFO: Expecting 41600 events.
[15:36:24.838] INFO: 41600 events read in total (2655ms).
[15:36:24.839] INFO: Test took 3899ms.
[15:36:24.868] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:25.603] INFO: Expecting 41600 events.
[15:36:28.795] INFO: 41600 events read in total (2666ms).
[15:36:28.796] INFO: Test took 3911ms.
[15:36:28.825] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:29.556] INFO: Expecting 41600 events.
[15:36:32.746] INFO: 41600 events read in total (2663ms).
[15:36:32.747] INFO: Test took 3904ms.
[15:36:32.777] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:33.511] INFO: Expecting 41600 events.
[15:36:36.717] INFO: 41600 events read in total (2679ms).
[15:36:36.718] INFO: Test took 3923ms.
[15:36:36.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:37.481] INFO: Expecting 41600 events.
[15:36:40.685] INFO: 41600 events read in total (2677ms).
[15:36:40.686] INFO: Test took 3921ms.
[15:36:40.716] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:41.445] INFO: Expecting 41600 events.
[15:36:44.626] INFO: 41600 events read in total (2654ms).
[15:36:44.627] INFO: Test took 3894ms.
[15:36:44.658] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:45.391] INFO: Expecting 41600 events.
[15:36:48.581] INFO: 41600 events read in total (2663ms).
[15:36:48.582] INFO: Test took 3907ms.
[15:36:48.612] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:49.342] INFO: Expecting 41600 events.
[15:36:52.509] INFO: 41600 events read in total (2640ms).
[15:36:52.510] INFO: Test took 3881ms.
[15:36:52.540] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:53.275] INFO: Expecting 41600 events.
[15:36:56.323] INFO: 41600 events read in total (2521ms).
[15:36:56.324] INFO: Test took 3766ms.
[15:36:56.354] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:56.724] INFO: enter test to run
[15:37:02.184] INFO: test: HighRate no parameter change
[15:37:02.184] INFO: running: highrate
[15:37:02.185] INFO: ----------------------------------------------------------------------
[15:37:02.185] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:37:02.185] INFO: ----------------------------------------------------------------------
[15:37:02.803] INFO: Expecting 208000 events.
[15:37:14.406] INFO: 208000 events read in total (11076ms).
[15:37:14.409] INFO: Test took 12216ms.
[15:37:14.532] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:14.776] INFO: number of dead pixels (per ROC): 17 1 1 0 0 0 0 0 0 0 0 0 0 0 0 2
[15:37:14.776] INFO: number of red-efficiency pixels: 83 33 60 108 101 88 94 61 74 100 100 78 87 54 19 25
[15:37:14.776] INFO: number of X-ray hits detected: 48921 31665 52190 82573 89517 91730 94979 69436 71739 91445 86379 71944 78792 46804 18434 20904
[15:37:14.776] 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:37:14.776] INFO: number of Vcal hits detected: 207079 207918 207891 207892 207897 207910 207904 207937 207925 207899 207900 207920 207912 207945 207979 207875
[15:37:14.776] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[15:37:14.776] INFO: Vcal hit overall efficiency (%): 99.6 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 99.9
[15:37:14.776] INFO: X-ray hit rate [MHz/cm2]: 14.3 9.3 15.3 24.2 26.2 26.9 27.8 20.4 21.0 26.8 25.3 21.1 23.1 13.7 5.4 6.1
[15:37:14.776] INFO: PixTestHighRate::doXPixelAlive() done
[15:37:14.829] INFO: PixTest:: pg_setup set to default.
[15:37:14.845] INFO: enter test to run
[15:37:35.304] INFO: test: HighRate no parameter change
[15:37:35.304] INFO: running: highrate
[15:37:35.305] INFO: ----------------------------------------------------------------------
[15:37:35.305] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:37:35.305] INFO: ----------------------------------------------------------------------
[15:37:35.923] INFO: Expecting 208000 events.
[15:37:49.148] INFO: 208000 events read in total (12698ms).
[15:37:49.152] INFO: Test took 13838ms.
[15:37:49.427] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:49.719] INFO: number of dead pixels (per ROC): 17 1 1 0 0 0 0 0 0 0 0 0 0 0 0 2
[15:37:49.719] INFO: number of red-efficiency pixels: 175 88 178 362 301 306 306 225 205 378 347 247 236 135 56 52
[15:37:49.719] INFO: number of X-ray hits detected: 106133 70091 114886 181218 197626 202861 208845 151915 159013 200133 190045 158221 173733 103209 41556 45063
[15:37:49.719] 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:37:49.719] INFO: number of Vcal hits detected: 206987 207862 207766 207617 207682 207677 207668 207763 207784 207592 207631 207740 207749 207857 207943 207849
[15:37:49.719] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[15:37:49.719] INFO: Vcal hit overall efficiency (%): 99.5 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 99.9
[15:37:49.720] INFO: X-ray hit rate [MHz/cm2]: 31.1 20.5 33.7 53.1 57.9 59.5 61.2 44.5 46.6 58.7 55.7 46.4 50.9 30.3 12.2 13.2
[15:37:49.720] INFO: PixTestHighRate::doXPixelAlive() done
[15:37:49.769] INFO: PixTest:: pg_setup set to default.
[15:37:49.782] INFO: enter test to run
[15:38:12.264] INFO: test: HighRate no parameter change
[15:38:12.264] INFO: running: highrate
[15:38:12.265] INFO: ----------------------------------------------------------------------
[15:38:12.265] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:38:12.265] INFO: ----------------------------------------------------------------------
[15:38:12.882] INFO: Expecting 208000 events.
[15:38:27.710] INFO: 208000 events read in total (14301ms).
[15:38:27.717] INFO: Test took 15442ms.
[15:38:28.115] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:28.452] INFO: number of dead pixels (per ROC): 17 1 1 0 0 0 0 0 0 0 0 0 0 0 0 2
[15:38:28.452] INFO: number of red-efficiency pixels: 316 138 334 752 662 683 738 481 450 918 806 421 472 280 83 89
[15:38:28.452] INFO: number of X-ray hits detected: 157452 105193 171993 271728 295831 302422 311735 227304 237742 300097 284879 236115 259547 154020 61481 68036
[15:38:28.452] 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:38:28.452] INFO: number of Vcal hits detected: 206822 207808 207578 207108 207253 207215 207147 207448 207501 206895 207034 207526 207473 207694 207915 207812
[15:38:28.452] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.6 99.7 99.7 99.6 99.8 99.8 99.5 99.6 99.8 99.8 99.9 100.0 100.0
[15:38:28.452] INFO: Vcal hit overall efficiency (%): 99.4 99.9 99.8 99.6 99.6 99.6 99.6 99.7 99.8 99.5 99.5 99.8 99.7 99.9 100.0 99.9
[15:38:28.452] INFO: X-ray hit rate [MHz/cm2]: 46.2 30.8 50.4 79.6 86.7 88.6 91.4 66.6 69.7 88.0 83.5 69.2 76.1 45.1 18.0 19.9
[15:38:28.452] INFO: PixTestHighRate::doXPixelAlive() done
[15:38:28.500] INFO: PixTest:: pg_setup set to default.
[15:38:28.517] INFO: enter test to run
[15:39:06.807] INFO: test: exit no parameter change
[15:39:07.068] QUIET: Connection to board 32 closed.
[15:39:07.070] INFO: pXar: this is the end, my friend