[11:27:39.757] INFO: *** Welcome to pxar ***
[11:27:39.757] INFO: *** Today: 2016/04/06
[11:27:39.834] INFO: *** Version: v1.9.0-796-gef167-dirty
[11:27:39.834] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//dacParameters35_C15.dat
[11:27:39.867] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:27:39.867] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//defaultMaskFile.dat
[11:27:39.868] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C15.dat
[11:27:39.969] INFO: clk: 4
[11:27:39.969] INFO: ctr: 4
[11:27:39.969] INFO: sda: 19
[11:27:39.969] INFO: tin: 9
[11:27:39.969] INFO: level: 15
[11:27:39.969] INFO: triggerdelay: 0
[11:27:39.969] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[11:27:39.969] INFO: Log level: INFO
[11:27:39.988] QUIET: Connection to board DTB_WREKRL opened.
[11:27:39.992] 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:
------------------------------------------------------
[11:27:39.995] INFO: RPC call hashes of host and DTB match: 398089610
[11:27:41.524] INFO: DUT info:
[11:27:41.524] INFO: The DUT currently contains the following objects:
[11:27:41.525] INFO: 2 TBM Cores tbm08c (2 ON)
[11:27:41.525] INFO: TBM Core alpha (0): 7 registers set
[11:27:41.525] INFO: TBM Core beta (1): 7 registers set
[11:27:41.525] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:27:41.525] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.525] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.525] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.525] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.525] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.525] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.525] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.525] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.526] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.526] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.526] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.526] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.526] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.526] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.526] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.526] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:41.930] INFO: enter 'restricted' command line mode
[11:27:41.930] INFO: enter test to run
[11:27:49.350] INFO: test: PixelAlive no parameter change
[11:27:49.350] INFO: running: pixelalive
[11:27:49.359] INFO: ----------------------------------------------------------------------
[11:27:49.359] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:27:49.359] INFO: ----------------------------------------------------------------------
[11:27:49.677] INFO: Expecting 41600 events.
[11:27:54.030] INFO: 41600 events read in total (3634ms).
[11:27:54.196] INFO: Test took 4834ms.
[11:27:54.210] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:54.473] INFO: PixTestAlive::aliveTest() done
[11:27:54.473] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 1 0 1
[11:27:54.507] INFO: enter test to run
[11:29:41.861] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[11:29:41.861] INFO: running: highrate
[11:29:41.862] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[11:29:42.097] INFO: ----------------------------------------------------------------------
[11:29:42.097] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[11:29:42.097] INFO: ----------------------------------------------------------------------
[11:29:42.097] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[11:29:42.097] INFO: edge/corner pixel THR is adjusted
[11:29:42.097] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:29:43.056] INFO: Collecting data for 5 seconds...
[11:29:48.072] INFO: Done with hot pixel readout
[11:29:59.988] INFO: PixTest:: pg_setup set to default.
[11:29:59.989] INFO: 1 hot pixels found in step 0
[11:30:00.982] INFO: Collecting data for 5 seconds...
[11:30:05.999] INFO: Done with hot pixel readout
[11:30:17.902] INFO: PixTest:: pg_setup set to default.
[11:30:17.903] INFO: 1 hot pixels found in step 1
[11:30:18.900] INFO: Collecting data for 5 seconds...
[11:30:23.916] INFO: Done with hot pixel readout
[11:30:35.841] INFO: PixTest:: pg_setup set to default.
[11:30:35.842] INFO: 3 hot pixels found in step 2
[11:30:36.835] INFO: Collecting data for 5 seconds...
[11:30:41.852] INFO: Done with hot pixel readout
[11:30:53.766] INFO: PixTest:: pg_setup set to default.

[11:30:53.767] INFO: 4 hot pixels found in step 3
[11:30:54.761] INFO: Collecting data for 5 seconds...
[11:30:59.777] INFO: Done with hot pixel readout
[11:31:11.723] INFO: PixTest:: pg_setup set to default.
[11:31:11.724] INFO: 6 hot pixels found in step 4
[11:31:12.718] INFO: Collecting data for 5 seconds...
[11:31:17.734] INFO: Done with hot pixel readout
[11:31:29.658] INFO: PixTest:: pg_setup set to default.
[11:31:29.659] INFO: 0 hot pixels found in step 5
[11:31:29.696] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:31:29.700] INFO: PixTest::trimHotPixels() done
[11:31:29.700] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C0.dat
[11:31:29.705] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C1.dat
[11:31:29.712] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C2.dat
[11:31:29.718] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C3.dat
[11:31:29.724] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C4.dat
[11:31:29.729] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C5.dat
[11:31:29.734] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C6.dat
[11:31:29.739] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C7.dat
[11:31:29.744] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C8.dat
[11:31:29.750] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C9.dat
[11:31:29.755] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C10.dat
[11:31:29.760] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C11.dat
[11:31:29.765] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C12.dat
[11:31:29.770] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C13.dat
[11:31:29.775] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C14.dat
[11:31:29.781] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C15.dat
[11:31:29.790] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//defaultMaskFile.dat
[11:31:29.802] INFO: enter test to run
[11:33:44.827] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[11:33:44.827] INFO: running: highrate
[11:33:44.831] INFO: ----------------------------------------------------------------------
[11:33:44.831] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:33:44.831] INFO: ----------------------------------------------------------------------
[11:33:44.831] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:33:44.831] INFO: edge/corner pixel THR is adjusted
[11:33:44.831] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:33:45.787] INFO: Collecting data for 1 seconds...
[11:33:46.790] INFO: Done with hot pixel readout
[11:33:50.254] INFO: PixTest:: pg_setup set to default.
[11:33:50.254] INFO: 0 hot pixels found in step 0
[11:33:50.260] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:33:50.348] INFO: PixTest::trimHotPixels() done
[11:33:50.348] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C0.dat
[11:33:50.353] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C1.dat
[11:33:50.359] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C2.dat
[11:33:50.364] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C3.dat
[11:33:50.370] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C4.dat
[11:33:50.375] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C5.dat
[11:33:50.380] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C6.dat
[11:33:50.386] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C7.dat
[11:33:50.391] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C8.dat
[11:33:50.397] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C9.dat
[11:33:50.402] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C10.dat
[11:33:50.408] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C11.dat
[11:33:50.413] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C12.dat
[11:33:50.419] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C13.dat
[11:33:50.424] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C14.dat
[11:33:50.429] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//trimParameters35_C15.dat
[11:33:50.435] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-4-43_FPIXTest-17C-FNAL-160401-0919_2016-04-01_09h19m_1459520387/000_FPIXTest_p17//defaultMaskFile.dat
[11:33:50.445] INFO: enter test to run
[11:34:10.779] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:34:10.779] INFO: running: xray
[11:34:10.781] INFO: ----------------------------------------------------------------------
[11:34:10.781] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:34:10.781] INFO: ----------------------------------------------------------------------
[11:34:11.744] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:34:23.012] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:34:52.307] INFO: Resuming triggers.
[11:35:03.578] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[11:35:33.035] INFO: Resuming triggers.
[11:35:44.309] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[11:36:13.677] INFO: Resuming triggers.
[11:36:24.948] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[11:36:54.358] INFO: Resuming triggers.
[11:37:05.630] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[11:37:35.017] INFO: Resuming triggers.
[11:37:46.289] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[11:38:15.692] INFO: Resuming triggers.
[11:38:26.964] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[11:38:56.351] INFO: Resuming triggers.
[11:39:07.620] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[11:39:37.009] INFO: Resuming triggers.
[11:39:47.161] INFO: data taking finished, elapsed time: 100 seconds.
[11:40:13.665] INFO: PixTest:: pg_setup set to default.
[11:40:13.668] INFO: PixTestXray::doPhRun() done
[11:40:13.848] INFO: enter test to run
[11:41:06.584] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:41:06.584] INFO: running: xray
[11:41:06.586] INFO: ----------------------------------------------------------------------
[11:41:06.586] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:41:06.586] INFO: ----------------------------------------------------------------------
[11:41:07.562] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:41:13.935] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[11:41:43.973] INFO: Resuming triggers.
[11:41:50.344] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[11:42:20.360] INFO: Resuming triggers.
[11:42:26.734] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[11:42:56.748] INFO: Resuming triggers.
[11:43:03.122] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[11:43:32.998] INFO: Resuming triggers.
[11:43:39.371] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[11:44:06.820] INFO: Resuming triggers.
[11:44:13.190] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[11:44:43.171] INFO: Resuming triggers.
[11:44:49.543] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[11:45:19.478] INFO: Resuming triggers.
[11:45:25.855] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[11:45:55.828] INFO: Resuming triggers.
[11:46:02.201] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[11:46:32.153] INFO: Resuming triggers.
[11:46:38.526] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[11:47:08.513] INFO: Resuming triggers.
[11:47:14.889] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[11:47:44.905] INFO: Resuming triggers.
[11:47:51.279] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[11:48:21.196] INFO: Resuming triggers.
[11:48:27.571] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[11:48:57.498] INFO: Resuming triggers.
[11:49:03.873] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[11:49:34.195] INFO: Resuming triggers.
[11:49:40.567] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[11:50:10.662] INFO: Resuming triggers.
[11:50:15.387] INFO: data taking finished, elapsed time: 100 seconds.
[11:50:37.694] INFO: PixTest:: pg_setup set to default.
[11:50:37.698] INFO: PixTestXray::doPhRun() done
[11:50:37.853] INFO: enter test to run
[11:50:52.724] INFO: test: HighRate no parameter change
[11:50:52.724] INFO: running: highrate
[11:50:52.725] INFO: ----------------------------------------------------------------------
[11:50:52.725] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:50:52.725] INFO: ----------------------------------------------------------------------
[11:50:52.865] INFO: Expecting 768 events.
[11:50:53.995] INFO: 768 events read in total (414ms).
[11:50:53.995] INFO: Test took 1264ms.
[11:50:54.798] INFO: Expecting 41600 events.
[11:50:57.828] INFO: 41600 events read in total (2503ms).
[11:50:57.829] INFO: Test took 3825ms.
[11:50:57.863] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:50:58.590] INFO: Expecting 41600 events.
[11:51:01.766] INFO: 41600 events read in total (2649ms).
[11:51:01.767] INFO: Test took 3885ms.
[11:51:01.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:02.520] INFO: Expecting 41600 events.
[11:51:05.757] INFO: 41600 events read in total (2710ms).
[11:51:05.758] INFO: Test took 3936ms.
[11:51:05.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:06.512] INFO: Expecting 41600 events.
[11:51:09.776] INFO: 41600 events read in total (2737ms).
[11:51:09.777] INFO: Test took 3964ms.
[11:51:09.813] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:10.535] INFO: Expecting 41600 events.
[11:51:13.757] INFO: 41600 events read in total (2695ms).
[11:51:13.758] INFO: Test took 3926ms.
[11:51:13.794] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:14.512] INFO: Expecting 41600 events.
[11:51:17.743] INFO: 41600 events read in total (2704ms).
[11:51:17.744] INFO: Test took 3932ms.
[11:51:17.780] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:18.500] INFO: Expecting 41600 events.
[11:51:21.771] INFO: 41600 events read in total (2744ms).
[11:51:21.772] INFO: Test took 3972ms.
[11:51:21.809] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:22.525] INFO: Expecting 41600 events.
[11:51:25.770] INFO: 41600 events read in total (2718ms).
[11:51:25.771] INFO: Test took 3943ms.
[11:51:25.807] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:26.527] INFO: Expecting 41600 events.
[11:51:29.767] INFO: 41600 events read in total (2713ms).
[11:51:29.768] INFO: Test took 3941ms.
[11:51:29.804] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:30.524] INFO: Expecting 41600 events.
[11:51:33.832] INFO: 41600 events read in total (2781ms).
[11:51:33.833] INFO: Test took 4011ms.
[11:51:33.869] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:34.586] INFO: Expecting 41600 events.
[11:51:37.827] INFO: 41600 events read in total (2714ms).
[11:51:37.828] INFO: Test took 3941ms.
[11:51:37.865] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:38.582] INFO: Expecting 41600 events.
[11:51:41.847] INFO: 41600 events read in total (2738ms).
[11:51:41.848] INFO: Test took 3964ms.
[11:51:41.885] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:42.601] INFO: Expecting 41600 events.
[11:51:45.881] INFO: 41600 events read in total (2754ms).
[11:51:45.882] INFO: Test took 3977ms.
[11:51:45.918] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:46.638] INFO: Expecting 41600 events.
[11:51:49.924] INFO: 41600 events read in total (2760ms).
[11:51:49.925] INFO: Test took 3988ms.
[11:51:49.962] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:50.680] INFO: Expecting 41600 events.
[11:51:53.935] INFO: 41600 events read in total (2729ms).
[11:51:53.936] INFO: Test took 3955ms.
[11:51:53.972] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:54.693] INFO: Expecting 41600 events.
[11:51:57.933] INFO: 41600 events read in total (2713ms).
[11:51:57.934] INFO: Test took 3943ms.
[11:51:57.971] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:58.692] INFO: Expecting 41600 events.
[11:52:01.963] INFO: 41600 events read in total (2744ms).
[11:52:01.964] INFO: Test took 3975ms.
[11:52:01.000] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:52:02.721] INFO: Expecting 41600 events.
[11:52:05.883] INFO: 41600 events read in total (2636ms).
[11:52:05.885] INFO: Test took 3867ms.
[11:52:05.923] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:52:06.641] INFO: Expecting 41600 events.
[11:52:09.823] INFO: 41600 events read in total (2655ms).
[11:52:09.824] INFO: Test took 3881ms.
[11:52:09.860] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:52:10.581] INFO: Expecting 41600 events.
[11:52:13.407] INFO: 41600 events read in total (2299ms).
[11:52:13.408] INFO: Test took 3528ms.
[11:52:13.444] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:52:13.802] INFO: enter test to run
[11:53:14.971] INFO: test: HighRate no parameter change
[11:53:14.971] INFO: running: highrate
[11:53:14.972] INFO: ----------------------------------------------------------------------
[11:53:14.972] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:53:14.972] INFO: ----------------------------------------------------------------------
[11:53:15.590] INFO: Expecting 208000 events.
[11:53:27.513] INFO: 208000 events read in total (11397ms).
[11:53:27.516] INFO: Test took 12535ms.
[11:53:27.672] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:53:27.928] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 1 0 1
[11:53:27.928] INFO: number of red-efficiency pixels: 69 53 90 132 157 178 157 86 94 127 164 128 96 54 21 22
[11:53:27.928] INFO: number of X-ray hits detected: 66806 44127 71428 111152 118965 126300 125842 93997 86944 108366 104729 88684 89246 53891 23448 24759
[11:53:27.928] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:53:27.928] INFO: number of Vcal hits detected: 207931 207947 207860 207864 207837 207817 207841 207913 207905 207872 207833 207870 207903 207896 207978 207929
[11:53:27.928] 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 99.9 100.0 100.0 100.0 100.0
[11:53:27.928] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[11:53:27.928] INFO: X-ray hit rate [MHz/cm2]: 19.6 12.9 20.9 32.6 34.9 37.0 36.9 27.6 25.5 31.8 30.7 26.0 26.2 15.8 6.9 7.3
[11:53:27.928] INFO: PixTestHighRate::doXPixelAlive() done
[11:53:27.972] INFO: PixTest:: pg_setup set to default.
[11:53:27.983] INFO: enter test to run
[11:53:53.939] INFO: test: HighRate no parameter change
[11:53:53.939] INFO: running: highrate
[11:53:53.940] INFO: ----------------------------------------------------------------------
[11:53:53.940] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:53:53.940] INFO: ----------------------------------------------------------------------
[11:53:54.560] INFO: Expecting 208000 events.
[11:54:06.423] INFO: 208000 events read in total (11336ms).
[11:54:06.428] INFO: Test took 12480ms.
[11:54:06.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:54:07.054] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 1 0 1
[11:54:07.054] INFO: number of red-efficiency pixels: 190 180 257 515 601 616 500 445 239 391 488 364 324 151 58 78
[11:54:07.054] INFO: number of X-ray hits detected: 136781 90696 146433 228814 245241 258783 257524 193156 179260 224116 215001 183426 183959 111216 47882 50737
[11:54:07.054] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:54:07.054] INFO: number of Vcal hits detected: 207799 207813 207672 207430 207327 207296 207453 207513 207748 207570 207473 207609 207661 207792 207941 207873
[11:54:07.054] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.7 99.7 99.7 99.8 99.8 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[11:54:07.054] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.7 99.7 99.8 99.9 99.8 99.7 99.8 99.8 99.9 100.0 99.9
[11:54:07.054] INFO: X-ray hit rate [MHz/cm2]: 40.1 26.6 42.9 67.1 71.9 75.9 75.5 56.6 52.5 65.7 63.0 53.8 53.9 32.6 14.0 14.9
[11:54:07.054] INFO: PixTestHighRate::doXPixelAlive() done
[11:54:07.105] INFO: PixTest:: pg_setup set to default.
[11:54:07.114] INFO: enter test to run
[11:54:18.538] INFO: test: HighRate no parameter change
[11:54:18.539] INFO: running: highrate
[11:54:18.540] INFO: ----------------------------------------------------------------------
[11:54:18.540] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:54:18.540] INFO: ----------------------------------------------------------------------
[11:54:19.155] INFO: Expecting 208000 events.
[11:54:35.010] INFO: 208000 events read in total (15328ms).
[11:54:35.017] INFO: Test took 16467ms.
[11:54:35.515] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:54:35.874] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 1 0 1
[11:54:35.874] INFO: number of red-efficiency pixels: 347 306 521 1290 1410 1537 1069 935 558 843 1095 826 749 372 95 104
[11:54:35.874] INFO: number of X-ray hits detected: 209049 138101 222076 345780 371698 392443 390224 293267 273030 337074 327362 278666 279843 168210 73426 77664
[11:54:35.874] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:54:35.874] INFO: number of Vcal hits detected: 207613 207657 207304 206349 206124 205858 206641 206748 207373 207003 206620 207025 207128 207531 207901 207845
[11:54:35.874] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.3 99.2 99.1 99.4 99.5 99.7 99.6 99.4 99.6 99.6 99.8 100.0 100.0
[11:54:35.874] INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.7 99.2 99.1 99.0 99.3 99.4 99.7 99.5 99.3 99.5 99.6 99.8 100.0 99.9
[11:54:35.874] INFO: X-ray hit rate [MHz/cm2]: 61.3 40.5 65.1 101.4 108.9 115.0 114.4 86.0 80.0 98.8 96.0 81.7 82.0 49.3 21.5 22.8
[11:54:35.874] INFO: PixTestHighRate::doXPixelAlive() done
[11:54:35.922] INFO: PixTest:: pg_setup set to default.
[11:54:35.938] INFO: enter test to run
[11:54:38.490] INFO: test: exit no parameter change
[11:54:38.849] QUIET: Connection to board 33 closed.
[11:54:38.850] INFO: pXar: this is the end, my friend