[14:05:16.144] INFO: *** Welcome to pxar ***
[14:05:16.144] INFO: *** Today: 2016/06/24
[14:05:16.172] INFO: *** Version: v1.9.0-814-g7497
[14:05:16.172] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//dacParameters35_C15.dat
[14:05:16.208] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:05:16.208] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//defaultMaskFile.dat
[14:05:16.210] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C15.dat
[14:05:16.307] INFO: clk: 4
[14:05:16.307] INFO: ctr: 4
[14:05:16.307] INFO: sda: 19
[14:05:16.307] INFO: tin: 9
[14:05:16.307] INFO: level: 15
[14:05:16.307] INFO: triggerdelay: 0
[14:05:16.307] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[14:05:16.307] INFO: Log level: INFO
[14:05:16.323] QUIET: Connection to board DTB_WREKRL opened.
[14:05:16.326] 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:
------------------------------------------------------
[14:05:16.329] INFO: RPC call hashes of host and DTB match: 398089610
[14:05:17.863] INFO: DUT info:
[14:05:17.863] INFO: The DUT currently contains the following objects:
[14:05:17.863] INFO: 2 TBM Cores tbm08c (2 ON)
[14:05:17.863] INFO: TBM Core alpha (0): 7 registers set
[14:05:17.863] INFO: TBM Core beta (1): 7 registers set
[14:05:17.863] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:05:17.863] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:17.863] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:17.863] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:17.863] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:17.863] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:17.864] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:17.864] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:17.864] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:17.864] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:17.864] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:17.864] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:17.864] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:17.864] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:17.864] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:17.864] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:17.864] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:05:18.267] INFO: enter 'restricted' command line mode
[14:05:18.267] INFO: enter test to run
[14:05:28.118] INFO: test: PixelAlive no parameter change
[14:05:28.118] INFO: running: pixelalive
[14:05:28.221] INFO: ----------------------------------------------------------------------
[14:05:28.221] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:05:28.221] INFO: ----------------------------------------------------------------------
[14:05:28.559] INFO: Expecting 41600 events.
[14:05:32.907] INFO: 41600 events read in total (3630ms).
[14:05:33.058] INFO: Test took 4833ms.
[14:05:33.072] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:33.449] INFO: PixTestAlive::aliveTest() done
[14:05:33.449] INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 0 0 0 0 0 0 29 52 128
[14:05:33.487] INFO: enter test to run
[14:06:11.062] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:06:11.062] INFO: running: highrate
[14:06:11.062] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:06:11.319] INFO: ----------------------------------------------------------------------
[14:06:11.319] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:06:11.319] INFO: ----------------------------------------------------------------------
[14:06:11.319] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:06:11.319] INFO: edge/corner pixel THR is adjusted
[14:06:11.319] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:06:12.278] INFO: Collecting data for 5 seconds...
[14:06:17.298] INFO: Done with hot pixel readout
[14:06:27.977] INFO: PixTest:: pg_setup set to default.
[14:06:27.978] INFO: 2 hot pixels found in step 0
[14:06:28.979] INFO: Collecting data for 5 seconds...
[14:06:33.997] INFO: Done with hot pixel readout
[14:06:44.868] INFO: PixTest:: pg_setup set to default.
[14:06:44.869] INFO: 1 hot pixels found in step 1
[14:06:45.854] INFO: Collecting data for 5 seconds...
[14:06:50.872] INFO: Done with hot pixel readout
[14:07:02.150] INFO: PixTest:: pg_setup set to default.
[14:07:02.151] INFO: 0 hot pixels found in step 2
[14:07:02.182] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:07:02.263] INFO: PixTest::trimHotPixels() done
[14:07:02.278] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C0.dat
[14:07:02.284] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C1.dat
[14:07:02.289] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C2.dat
[14:07:02.295] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C3.dat
[14:07:02.300] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C4.dat
[14:07:02.305] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C5.dat
[14:07:02.311] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C6.dat
[14:07:02.316] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C7.dat
[14:07:02.321] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C8.dat
[14:07:02.327] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C9.dat
[14:07:02.332] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C10.dat
[14:07:02.337] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C11.dat
[14:07:02.343] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C12.dat
[14:07:02.348] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C13.dat
[14:07:02.354] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C14.dat
[14:07:02.359] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C15.dat
[14:07:02.364] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//defaultMaskFile.dat
[14:07:02.379] INFO: enter test to run
[14:07:35.877] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:07:35.877] INFO: running: highrate
[14:07:35.882] INFO: ----------------------------------------------------------------------
[14:07:35.882] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:07:35.882] INFO: ----------------------------------------------------------------------
[14:07:35.882] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:07:35.882] INFO: edge/corner pixel THR is adjusted
[14:07:35.882] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:07:36.839] INFO: Collecting data for 1 seconds...
[14:07:37.843] INFO: Done with hot pixel readout
[14:07:41.550] INFO: PixTest:: pg_setup set to default.
[14:07:41.551] INFO: 0 hot pixels found in step 0
[14:07:41.557] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:07:41.656] INFO: PixTest::trimHotPixels() done
[14:07:41.657] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C0.dat
[14:07:41.668] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C1.dat
[14:07:41.674] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C2.dat
[14:07:41.680] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C3.dat
[14:07:41.685] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C4.dat
[14:07:41.690] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C5.dat
[14:07:41.696] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C6.dat
[14:07:41.701] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C7.dat
[14:07:41.706] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C8.dat
[14:07:41.712] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C9.dat
[14:07:41.717] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C10.dat
[14:07:41.723] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C11.dat
[14:07:41.728] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C12.dat
[14:07:41.733] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C13.dat
[14:07:41.739] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C14.dat
[14:07:41.744] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//trimParameters35_C15.dat
[14:07:41.749] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-1-15_FPIXTest-17C-FNAL-160623-1419_2016-06-23_14h20m_1466709614/000_FPIXTest_p17//defaultMaskFile.dat
[14:07:41.759] INFO: enter test to run
[14:08:02.061] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:08:02.061] INFO: running: xray
[14:08:02.071] INFO: ----------------------------------------------------------------------
[14:08:02.071] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:08:02.071] INFO: ----------------------------------------------------------------------
[14:08:03.060] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:08:15.224] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[14:08:43.998] INFO: Resuming triggers.
[14:08:56.162] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[14:09:25.124] INFO: Resuming triggers.
[14:09:37.287] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[14:10:06.362] INFO: Resuming triggers.
[14:10:18.523] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[14:10:47.659] INFO: Resuming triggers.
[14:10:59.822] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[14:11:29.060] INFO: Resuming triggers.
[14:11:41.223] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[14:12:10.434] INFO: Resuming triggers.
[14:12:22.595] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[14:12:51.731] INFO: Resuming triggers.
[14:13:03.894] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[14:13:32.968] INFO: Resuming triggers.
[14:13:35.000] INFO: data taking finished, elapsed time: 100 seconds.
[14:13:43.711] INFO: PixTest:: pg_setup set to default.
[14:13:43.715] INFO: PixTestXray::doPhRun() done
[14:13:43.928] INFO: enter test to run
[14:14:41.066] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:14:41.066] INFO: running: xray
[14:14:41.068] INFO: ----------------------------------------------------------------------
[14:14:41.068] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:14:41.068] INFO: ----------------------------------------------------------------------
[14:14:42.032] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:14:49.641] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[14:15:19.737] INFO: Resuming triggers.
[14:15:27.343] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[14:15:57.458] INFO: Resuming triggers.
[14:16:05.069] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[14:16:35.091] INFO: Resuming triggers.
[14:16:42.700] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[14:17:13.390] INFO: Resuming triggers.
[14:17:20.995] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[14:17:51.174] INFO: Resuming triggers.
[14:17:58.777] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[14:18:29.132] INFO: Resuming triggers.
[14:18:36.736] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[14:19:08.229] INFO: Resuming triggers.
[14:19:15.828] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[14:19:46.793] INFO: Resuming triggers.
[14:19:54.395] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[14:20:23.982] INFO: Resuming triggers.
[14:20:31.588] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[14:21:01.111] INFO: Resuming triggers.
[14:21:08.714] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[14:21:38.750] INFO: Resuming triggers.
[14:21:46.357] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[14:22:16.557] INFO: Resuming triggers.
[14:22:24.160] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[14:22:54.165] INFO: Resuming triggers.
[14:22:55.637] INFO: data taking finished, elapsed time: 100 seconds.
[14:23:01.788] INFO: PixTest:: pg_setup set to default.
[14:23:01.791] INFO: PixTestXray::doPhRun() done
[14:23:01.937] INFO: enter test to run
[14:24:15.631] INFO: test: HighRate no parameter change
[14:24:15.631] INFO: running: highrate
[14:24:15.681] INFO: ----------------------------------------------------------------------
[14:24:15.681] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:24:15.681] INFO: ----------------------------------------------------------------------
[14:24:15.848] INFO: Expecting 768 events.
[14:24:16.982] INFO: 768 events read in total (418ms).
[14:24:16.982] INFO: Test took 1280ms.
[14:24:17.786] INFO: Expecting 41600 events.
[14:24:20.826] INFO: 41600 events read in total (2514ms).
[14:24:20.827] INFO: Test took 3837ms.
[14:24:20.855] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:21.594] INFO: Expecting 41600 events.
[14:24:24.725] INFO: 41600 events read in total (2604ms).
[14:24:24.726] INFO: Test took 3856ms.
[14:24:24.754] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:25.489] INFO: Expecting 41600 events.
[14:24:28.660] INFO: 41600 events read in total (2645ms).
[14:24:28.661] INFO: Test took 3892ms.
[14:24:28.689] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:29.421] INFO: Expecting 41600 events.
[14:24:32.611] INFO: 41600 events read in total (2663ms).
[14:24:32.612] INFO: Test took 3907ms.
[14:24:32.640] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:33.375] INFO: Expecting 41600 events.
[14:24:36.559] INFO: 41600 events read in total (2658ms).
[14:24:36.560] INFO: Test took 3903ms.
[14:24:36.589] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:37.324] INFO: Expecting 41600 events.
[14:24:40.494] INFO: 41600 events read in total (2643ms).
[14:24:40.495] INFO: Test took 3891ms.
[14:24:40.524] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:41.258] INFO: Expecting 41600 events.
[14:24:44.445] INFO: 41600 events read in total (2660ms).
[14:24:44.446] INFO: Test took 3905ms.
[14:24:44.474] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:45.211] INFO: Expecting 41600 events.
[14:24:48.400] INFO: 41600 events read in total (2662ms).
[14:24:48.401] INFO: Test took 3910ms.
[14:24:48.430] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:49.161] INFO: Expecting 41600 events.
[14:24:52.341] INFO: 41600 events read in total (2653ms).
[14:24:52.341] INFO: Test took 3895ms.
[14:24:52.370] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:53.106] INFO: Expecting 41600 events.
[14:24:56.298] INFO: 41600 events read in total (2665ms).
[14:24:56.299] INFO: Test took 3913ms.
[14:24:56.328] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:57.060] INFO: Expecting 41600 events.
[14:25:00.246] INFO: 41600 events read in total (2659ms).
[14:25:00.247] INFO: Test took 3904ms.
[14:25:00.275] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:01.009] INFO: Expecting 41600 events.
[14:25:04.183] INFO: 41600 events read in total (2647ms).
[14:25:04.184] INFO: Test took 3893ms.
[14:25:04.212] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:04.947] INFO: Expecting 41600 events.
[14:25:08.131] INFO: 41600 events read in total (2657ms).
[14:25:08.132] INFO: Test took 3904ms.
[14:25:08.160] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:08.896] INFO: Expecting 41600 events.
[14:25:12.076] INFO: 41600 events read in total (2653ms).
[14:25:12.077] INFO: Test took 3902ms.
[14:25:12.106] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:12.836] INFO: Expecting 41600 events.
[14:25:16.014] INFO: 41600 events read in total (2651ms).
[14:25:16.015] INFO: Test took 3892ms.
[14:25:16.044] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:16.776] INFO: Expecting 41600 events.
[14:25:19.952] INFO: 41600 events read in total (2649ms).
[14:25:19.952] INFO: Test took 3892ms.
[14:25:19.981] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:20.716] INFO: Expecting 41600 events.
[14:25:23.887] INFO: 41600 events read in total (2644ms).
[14:25:23.888] INFO: Test took 3892ms.
[14:25:23.917] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:24.652] INFO: Expecting 41600 events.
[14:25:27.823] INFO: 41600 events read in total (2644ms).
[14:25:27.824] INFO: Test took 3891ms.
[14:25:27.852] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:28.589] INFO: Expecting 41600 events.
[14:25:31.745] INFO: 41600 events read in total (2629ms).
[14:25:31.746] INFO: Test took 3877ms.
[14:25:31.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:32.510] INFO: Expecting 41600 events.
[14:25:35.524] INFO: 41600 events read in total (2488ms).
[14:25:35.525] INFO: Test took 3734ms.
[14:25:35.553] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:25:35.926] INFO: enter test to run
[14:26:02.462] INFO: test: HighRate no parameter change
[14:26:02.462] INFO: running: highrate
[14:26:02.463] INFO: ----------------------------------------------------------------------
[14:26:02.463] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:26:02.463] INFO: ----------------------------------------------------------------------
[14:26:03.078] INFO: Expecting 208000 events.
[14:26:14.655] INFO: 208000 events read in total (11050ms).
[14:26:14.657] INFO: Test took 12184ms.
[14:26:14.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:15.017] INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 0 0 0 0 0 0 27 52 126
[14:26:15.017] INFO: number of red-efficiency pixels: 61 33 62 80 103 122 121 89 69 109 96 90 63 71 68 159
[14:26:15.017] INFO: number of X-ray hits detected: 45741 30490 47934 79968 88038 93531 93886 68769 61968 87223 84283 69740 65241 40933 13657 16268
[14:26:15.017] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:26:15.017] INFO: number of Vcal hits detected: 207840 207965 207936 207920 207894 207876 207877 207898 207927 207889 207903 207907 207915 206473 205384 201524
[14:26:15.017] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 99.9 100.0 100.0
[14:26:15.017] INFO: Vcal hit overall efficiency (%): 99.9 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 99.9 100.0 100.0 100.0 99.3 98.7 96.9
[14:26:15.017] INFO: X-ray hit rate [MHz/cm2]: 13.4 8.9 14.0 23.4 25.8 27.4 27.5 20.2 18.2 25.6 24.7 20.4 19.1 12.0 4.0 4.8
[14:26:15.017] INFO: PixTestHighRate::doXPixelAlive() done
[14:26:15.062] INFO: PixTest:: pg_setup set to default.
[14:26:15.077] INFO: enter test to run
[14:26:32.934] INFO: test: HighRate no parameter change
[14:26:32.934] INFO: running: highrate
[14:26:32.935] INFO: ----------------------------------------------------------------------
[14:26:32.935] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:26:32.935] INFO: ----------------------------------------------------------------------
[14:26:33.547] INFO: Expecting 208000 events.
[14:26:46.453] INFO: 208000 events read in total (12379ms).
[14:26:46.458] INFO: Test took 13514ms.
[14:26:46.692] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:46.973] INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 0 0 0 0 0 0 28 52 126
[14:26:46.973] INFO: number of red-efficiency pixels: 97 74 164 261 337 345 411 305 173 331 316 254 194 191 93 186
[14:26:46.973] INFO: number of X-ray hits detected: 95072 63096 100076 165395 183127 192636 195756 143722 129392 181524 175563 145479 135739 85482 28616 32779
[14:26:46.973] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:26:46.973] INFO: number of Vcal hits detected: 207801 207923 207829 207726 207643 207630 207563 207659 207824 207650 207666 207734 207785 206334 205359 201489
[14:26:46.973] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 99.9
[14:26:46.973] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.9 99.9 99.2 98.7 96.9
[14:26:46.973] INFO: X-ray hit rate [MHz/cm2]: 27.9 18.5 29.3 48.5 53.7 56.5 57.4 42.1 37.9 53.2 51.5 42.6 39.8 25.1 8.4 9.6
[14:26:46.973] INFO: PixTestHighRate::doXPixelAlive() done
[14:26:47.019] INFO: PixTest:: pg_setup set to default.
[14:26:47.032] INFO: enter test to run
[14:27:12.549] INFO: test: HighRate no parameter change
[14:27:12.549] INFO: running: highrate
[14:27:12.551] INFO: ----------------------------------------------------------------------
[14:27:12.551] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:27:12.551] INFO: ----------------------------------------------------------------------
[14:27:13.162] INFO: Expecting 208000 events.
[14:27:27.690] INFO: 208000 events read in total (14001ms).
[14:27:27.696] INFO: Test took 15137ms.
[14:27:28.072] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:28.395] INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 0 0 0 0 0 0 29 52 125
[14:27:28.396] INFO: number of red-efficiency pixels: 182 160 359 656 780 914 943 664 345 773 768 530 370 354 117 207
[14:27:28.396] INFO: number of X-ray hits detected: 148482 98930 155253 257711 287264 302677 304988 224735 201940 283054 275595 228030 211344 133534 45292 51188
[14:27:28.396] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:27:28.396] INFO: number of Vcal hits detected: 207707 207824 207597 207257 207094 206874 206830 207172 207631 207082 207087 207416 207582 206126 205334 201472
[14:27:28.396] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.5 99.5 99.6 99.8 99.6 99.6 99.8 99.8 99.8 100.0 99.9
[14:27:28.396] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.6 99.6 99.5 99.4 99.6 99.8 99.6 99.6 99.7 99.8 99.1 98.7 96.9
[14:27:28.396] INFO: X-ray hit rate [MHz/cm2]: 43.5 29.0 45.5 75.5 84.2 88.7 89.4 65.9 59.2 83.0 80.8 66.8 61.9 39.1 13.3 15.0
[14:27:28.396] INFO: PixTestHighRate::doXPixelAlive() done
[14:27:28.446] INFO: PixTest:: pg_setup set to default.
[14:27:28.459] INFO: enter test to run
[14:27:31.733] INFO: test: exit no parameter change
[14:27:32.067] QUIET: Connection to board 33 closed.
[14:27:32.078] INFO: pXar: this is the end, my friend