[13:38:32.743] INFO: *** Welcome to pxar ***
[13:38:32.743] INFO: *** Today: 2016/05/04
[13:38:33.175] INFO: *** Version: v1.9.0-796-gef167-dirty
[13:38:33.175] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//dacParameters35_C15.dat
[13:38:33.195] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:38:33.195] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//defaultMaskFile.dat
[13:38:33.195] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C15.dat
[13:38:33.343] INFO: clk: 4
[13:38:33.343] INFO: ctr: 4
[13:38:33.343] INFO: sda: 19
[13:38:33.343] INFO: tin: 9
[13:38:33.343] INFO: level: 15
[13:38:33.343] INFO: triggerdelay: 0
[13:38:33.343] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[13:38:33.343] INFO: Log level: INFO
[13:38:33.370] QUIET: Connection to board DTB_WREK4U opened.
[13:38:33.373] 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:
------------------------------------------------------
[13:38:33.381] INFO: RPC call hashes of host and DTB match: 398089610
[13:38:34.923] INFO: DUT info:
[13:38:34.923] INFO: The DUT currently contains the following objects:
[13:38:34.923] INFO: 2 TBM Cores tbm08c (2 ON)
[13:38:34.923] INFO: TBM Core alpha (0): 7 registers set
[13:38:34.923] INFO: TBM Core beta (1): 7 registers set
[13:38:34.923] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:38:34.923] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:34.923] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:34.924] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:34.924] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:34.924] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:34.924] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:34.924] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:34.924] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:34.924] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:34.924] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:34.924] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:34.924] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:34.924] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:34.924] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:34.924] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:34.924] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.327] INFO: enter 'restricted' command line mode
[13:38:35.328] INFO: enter test to run
[13:38:41.969] INFO: test: PixelAlive no parameter change
[13:38:41.969] INFO: running: pixelalive
[13:38:41.979] INFO: ----------------------------------------------------------------------
[13:38:41.979] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:38:41.979] INFO: ----------------------------------------------------------------------
[13:38:42.300] INFO: Expecting 41600 events.
[13:38:46.653] INFO: 41600 events read in total (3634ms).
[13:38:46.814] INFO: Test took 4833ms.
[13:38:46.828] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:47.090] INFO: PixTestAlive::aliveTest() done
[13:38:47.090] INFO: number of dead pixels (per ROC): 0 2 3 0 0 0 15 0 0 0 0 0 0 0 0 0
[13:38:47.124] INFO: enter test to run
[13:39:16.641] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:39:16.641] INFO: running: highrate
[13:39:16.642] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:39:16.921] INFO: ----------------------------------------------------------------------
[13:39:16.921] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:39:16.921] INFO: ----------------------------------------------------------------------
[13:39:16.921] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:39:16.921] INFO: edge/corner pixel THR is adjusted
[13:39:16.921] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:39:17.879] INFO: Collecting data for 5 seconds...
[13:39:22.899] INFO: Done with hot pixel readout
[13:39:34.641] INFO: PixTest:: pg_setup set to default.
[13:39:34.642] INFO: 2 hot pixels found in step 0
[13:39:35.651] INFO: Collecting data for 5 seconds...
[13:39:40.671] INFO: Done with hot pixel readout
[13:39:52.226] INFO: PixTest:: pg_setup set to default.
[13:39:52.227] INFO: 1 hot pixels found in step 1
[13:39:53.218] INFO: Collecting data for 5 seconds...
[13:39:58.237] INFO: Done with hot pixel readout
[13:40:09.831] INFO: PixTest:: pg_setup set to default.
[13:40:09.832] INFO: 1 hot pixels found in step 2
[13:40:10.822] INFO: Collecting data for 5 seconds...
[13:40:15.840] INFO: Done with hot pixel readout
[13:40:27.380] INFO: PixTest:: pg_setup set to default.
[13:40:27.381] INFO: 1 hot pixels found in step 3
[13:40:28.370] INFO: Collecting data for 5 seconds...
[13:40:33.386] INFO: Done with hot pixel readout
[13:40:44.876] INFO: PixTest:: pg_setup set to default.
[13:40:44.877] INFO: 0 hot pixels found in step 4
[13:40:44.911] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:40:44.915] INFO: PixTest::trimHotPixels() done
[13:40:44.915] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C0.dat
[13:40:44.921] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C1.dat
[13:40:44.927] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C2.dat
[13:40:44.933] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C3.dat
[13:40:44.939] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C4.dat
[13:40:44.944] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C5.dat
[13:40:44.950] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C6.dat
[13:40:44.955] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C7.dat
[13:40:44.960] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C8.dat
[13:40:44.966] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C9.dat
[13:40:44.972] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C10.dat
[13:40:44.977] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C11.dat
[13:40:44.983] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C12.dat
[13:40:44.988] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C13.dat
[13:40:44.993] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C14.dat
[13:40:44.999] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C15.dat
[13:40:44.005] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//defaultMaskFile.dat
[13:40:45.015] INFO: enter test to run
[13:41:07.872] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:41:07.872] INFO: running: highrate
[13:41:07.877] INFO: ----------------------------------------------------------------------
[13:41:07.877] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:41:07.877] INFO: ----------------------------------------------------------------------
[13:41:07.877] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:41:07.877] INFO: edge/corner pixel THR is adjusted
[13:41:07.877] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:41:08.835] INFO: Collecting data for 1 seconds...
[13:41:09.838] INFO: Done with hot pixel readout
[13:41:13.723] INFO: PixTest:: pg_setup set to default.
[13:41:13.724] INFO: 0 hot pixels found in step 0
[13:41:13.729] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:41:13.822] INFO: PixTest::trimHotPixels() done
[13:41:13.822] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C0.dat
[13:41:13.835] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C1.dat
[13:41:13.841] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C2.dat
[13:41:13.847] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C3.dat
[13:41:13.852] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C4.dat
[13:41:13.857] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C5.dat
[13:41:13.863] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C6.dat
[13:41:13.868] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C7.dat
[13:41:13.873] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C8.dat
[13:41:13.878] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C9.dat
[13:41:13.884] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C10.dat
[13:41:13.889] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C11.dat
[13:41:13.894] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C12.dat
[13:41:13.899] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C13.dat
[13:41:13.905] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C14.dat
[13:41:13.910] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//trimParameters35_C15.dat
[13:41:13.915] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-14_FPIXTest-17C-FNAL-160502-0930_2016-05-02_09h30m_1462199444/000_FPIXTest_p17//defaultMaskFile.dat
[13:41:13.925] INFO: enter test to run
[13:41:42.792] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:41:42.792] INFO: running: xray
[13:41:42.793] INFO: ----------------------------------------------------------------------
[13:41:42.793] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:41:42.793] INFO: ----------------------------------------------------------------------
[13:41:43.756] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:41:55.840] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[13:42:24.896] INFO: Resuming triggers.
[13:42:36.984] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[13:43:06.266] INFO: Resuming triggers.
[13:43:18.352] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[13:43:47.536] INFO: Resuming triggers.
[13:43:59.623] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[13:44:28.838] INFO: Resuming triggers.
[13:44:40.929] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[13:45:10.212] INFO: Resuming triggers.
[13:45:22.299] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[13:45:51.641] INFO: Resuming triggers.
[13:46:03.734] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[13:46:32.996] INFO: Resuming triggers.
[13:46:45.085] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[13:47:14.306] INFO: Resuming triggers.
[13:47:17.933] INFO: data taking finished, elapsed time: 100 seconds.
[13:47:27.020] INFO: PixTest:: pg_setup set to default.
[13:47:27.023] INFO: PixTestXray::doPhRun() done
[13:47:27.156] INFO: enter test to run
[13:48:02.941] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:48:02.941] INFO: running: xray
[13:48:02.942] INFO: ----------------------------------------------------------------------
[13:48:02.942] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:48:02.942] INFO: ----------------------------------------------------------------------
[13:48:03.907] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:48:10.964] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[13:48:41.130] INFO: Resuming triggers.
[13:48:48.184] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[13:49:18.366] INFO: Resuming triggers.
[13:49:25.425] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[13:49:56.324] INFO: Resuming triggers.
[13:50:03.378] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[13:50:34.533] INFO: Resuming triggers.
[13:50:41.585] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[13:51:12.180] INFO: Resuming triggers.
[13:51:19.233] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[13:51:49.410] INFO: Resuming triggers.
[13:51:56.459] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[13:52:26.609] INFO: Resuming triggers.
[13:52:33.658] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[13:53:04.482] INFO: Resuming triggers.
[13:53:11.529] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[13:53:41.193] INFO: Resuming triggers.
[13:53:48.241] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[13:54:18.415] INFO: Resuming triggers.
[13:54:25.469] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[13:54:55.598] INFO: Resuming triggers.
[13:55:02.650] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[13:55:32.726] INFO: Resuming triggers.
[13:55:39.778] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[13:56:09.964] INFO: Resuming triggers.
[13:56:17.013] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[13:56:47.230] INFO: Resuming triggers.
[13:56:48.847] INFO: data taking finished, elapsed time: 100 seconds.
[13:56:56.126] INFO: PixTest:: pg_setup set to default.
[13:56:56.129] INFO: PixTestXray::doPhRun() done
[13:56:56.280] INFO: enter test to run
[13:57:37.505] INFO: test: HighRate no parameter change
[13:57:37.505] INFO: running: highrate
[13:57:37.519] INFO: ----------------------------------------------------------------------
[13:57:37.519] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:57:37.519] INFO: ----------------------------------------------------------------------
[13:57:37.675] INFO: Expecting 768 events.
[13:57:38.809] INFO: 768 events read in total (418ms).
[13:57:38.809] INFO: Test took 1269ms.
[13:57:39.613] INFO: Expecting 41600 events.
[13:57:42.519] INFO: 41600 events read in total (2380ms).
[13:57:42.520] INFO: Test took 3686ms.
[13:57:42.549] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:43.285] INFO: Expecting 41600 events.
[13:57:46.410] INFO: 41600 events read in total (2598ms).
[13:57:46.410] INFO: Test took 3846ms.
[13:57:46.440] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:47.174] INFO: Expecting 41600 events.
[13:57:50.275] INFO: 41600 events read in total (2574ms).
[13:57:50.276] INFO: Test took 3820ms.
[13:57:50.306] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:51.040] INFO: Expecting 41600 events.
[13:57:54.200] INFO: 41600 events read in total (2633ms).
[13:57:54.201] INFO: Test took 3880ms.
[13:57:54.232] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:54.967] INFO: Expecting 41600 events.
[13:57:58.147] INFO: 41600 events read in total (2653ms).
[13:57:58.148] INFO: Test took 3899ms.
[13:57:58.177] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:58.909] INFO: Expecting 41600 events.
[13:58:02.083] INFO: 41600 events read in total (2647ms).
[13:58:02.084] INFO: Test took 3890ms.
[13:58:02.113] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:02.846] INFO: Expecting 41600 events.
[13:58:06.041] INFO: 41600 events read in total (2668ms).
[13:58:06.042] INFO: Test took 3912ms.
[13:58:06.071] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:06.802] INFO: Expecting 41600 events.
[13:58:09.970] INFO: 41600 events read in total (2641ms).
[13:58:09.971] INFO: Test took 3884ms.
[13:58:09.000] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:10.736] INFO: Expecting 41600 events.
[13:58:13.915] INFO: 41600 events read in total (2652ms).
[13:58:13.916] INFO: Test took 3899ms.
[13:58:13.945] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:14.679] INFO: Expecting 41600 events.
[13:58:17.864] INFO: 41600 events read in total (2659ms).
[13:58:17.865] INFO: Test took 3903ms.
[13:58:17.894] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:18.628] INFO: Expecting 41600 events.
[13:58:21.825] INFO: 41600 events read in total (2670ms).
[13:58:21.826] INFO: Test took 3915ms.
[13:58:21.856] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:22.586] INFO: Expecting 41600 events.
[13:58:25.771] INFO: 41600 events read in total (2658ms).
[13:58:25.772] INFO: Test took 3899ms.
[13:58:25.801] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:26.535] INFO: Expecting 41600 events.
[13:58:29.715] INFO: 41600 events read in total (2654ms).
[13:58:29.716] INFO: Test took 3899ms.
[13:58:29.747] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:30.470] INFO: Expecting 41600 events.
[13:58:33.667] INFO: 41600 events read in total (2671ms).
[13:58:33.667] INFO: Test took 3903ms.
[13:58:33.697] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:34.428] INFO: Expecting 41600 events.
[13:58:37.627] INFO: 41600 events read in total (2672ms).
[13:58:37.628] INFO: Test took 3914ms.
[13:58:37.657] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:38.390] INFO: Expecting 41600 events.
[13:58:41.574] INFO: 41600 events read in total (2657ms).
[13:58:41.575] INFO: Test took 3901ms.
[13:58:41.604] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:42.336] INFO: Expecting 41600 events.
[13:58:45.514] INFO: 41600 events read in total (2651ms).
[13:58:45.514] INFO: Test took 3893ms.
[13:58:45.544] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:46.278] INFO: Expecting 41600 events.
[13:58:49.481] INFO: 41600 events read in total (2676ms).
[13:58:49.482] INFO: Test took 3921ms.
[13:58:49.511] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:50.239] INFO: Expecting 41600 events.
[13:58:53.409] INFO: 41600 events read in total (2643ms).
[13:58:53.410] INFO: Test took 3881ms.
[13:58:53.439] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:54.174] INFO: Expecting 41600 events.
[13:58:57.231] INFO: 41600 events read in total (2530ms).
[13:58:57.232] INFO: Test took 3777ms.
[13:58:57.261] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:57.626] INFO: enter test to run
[13:59:07.272] INFO: test: HighRate no parameter change
[13:59:07.272] INFO: running: highrate
[13:59:07.274] INFO: ----------------------------------------------------------------------
[13:59:07.274] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:59:07.274] INFO: ----------------------------------------------------------------------
[13:59:07.889] INFO: Expecting 208000 events.
[13:59:19.424] INFO: 208000 events read in total (11008ms).
[13:59:19.427] INFO: Test took 12147ms.
[13:59:19.547] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:19.794] INFO: number of dead pixels (per ROC): 0 2 3 0 0 0 15 0 0 0 0 0 0 0 0 0
[13:59:19.794] INFO: number of red-efficiency pixels: 59 52 70 109 95 112 138 68 69 95 94 98 72 55 20 21
[13:59:19.794] INFO: number of X-ray hits detected: 54882 36262 53138 86254 89254 92347 90084 62908 61826 79622 83387 75934 76440 45115 18532 20029
[13:59:19.794] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:59:19.794] INFO: number of Vcal hits detected: 207938 207849 207781 207889 207904 207883 207027 207932 207929 207903 207905 207897 207928 207945 207980 207979
[13:59:19.794] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[13:59:19.794] INFO: Vcal hit overall efficiency (%): 100.0 99.9 99.9 99.9 100.0 99.9 99.5 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[13:59:19.794] INFO: X-ray hit rate [MHz/cm2]: 16.1 10.6 15.6 25.3 26.2 27.1 26.4 18.4 18.1 23.3 24.4 22.3 22.4 13.2 5.4 5.9
[13:59:19.794] INFO: PixTestHighRate::doXPixelAlive() done
[13:59:19.842] INFO: PixTest:: pg_setup set to default.
[13:59:19.857] INFO: enter test to run
[13:59:39.168] INFO: test: HighRate no parameter change
[13:59:39.168] INFO: running: highrate
[13:59:39.169] INFO: ----------------------------------------------------------------------
[13:59:39.169] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:59:39.169] INFO: ----------------------------------------------------------------------
[13:59:39.785] INFO: Expecting 208000 events.
[13:59:53.088] INFO: 208000 events read in total (12776ms).
[13:59:53.094] INFO: Test took 13915ms.
[13:59:53.367] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:53.665] INFO: number of dead pixels (per ROC): 0 2 3 0 0 0 14 0 0 0 0 0 0 0 0 0
[13:59:53.665] INFO: number of red-efficiency pixels: 252 114 222 384 361 405 439 269 201 340 338 344 262 142 39 46
[13:59:53.665] INFO: number of X-ray hits detected: 128251 83966 123990 200754 206807 214245 209853 146490 144324 184002 192782 176379 177375 104698 44071 47031
[13:59:53.665] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:59:53.665] INFO: number of Vcal hits detected: 207727 207787 207612 207591 207610 207565 206696 207719 207792 207630 207637 207627 207734 207851 207960 207952
[13:59:53.665] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[13:59:53.665] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.8 99.4 99.9 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[13:59:53.665] INFO: X-ray hit rate [MHz/cm2]: 37.6 24.6 36.3 58.8 60.6 62.8 61.5 42.9 42.3 53.9 56.5 51.7 52.0 30.7 12.9 13.8
[13:59:53.665] INFO: PixTestHighRate::doXPixelAlive() done
[13:59:53.713] INFO: PixTest:: pg_setup set to default.
[13:59:53.729] INFO: enter test to run
[14:01:21.695] INFO: test: HighRate no parameter change
[14:01:21.696] INFO: running: highrate
[14:01:21.697] INFO: ----------------------------------------------------------------------
[14:01:21.697] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:01:21.697] INFO: ----------------------------------------------------------------------
[14:01:22.332] INFO: Expecting 208000 events.
[14:01:37.724] INFO: 208000 events read in total (14865ms).
[14:01:37.731] INFO: Test took 16024ms.
[14:01:38.144] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:38.492] INFO: number of dead pixels (per ROC): 0 2 3 0 0 0 16 0 0 0 0 0 0 0 0 0
[14:01:38.492] INFO: number of red-efficiency pixels: 480 248 522 940 896 964 1068 612 484 781 858 776 604 327 100 62
[14:01:38.492] INFO: number of X-ray hits detected: 194539 128627 188918 304398 313974 326398 317201 223812 219484 281228 294242 268718 270675 158945 66928 70388
[14:01:38.492] 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:01:38.492] INFO: number of Vcal hits detected: 207432 207634 207206 206888 206969 206803 205906 207278 207460 207094 206971 207089 207325 207629 207898 207938
[14:01:38.492] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.6 99.5 99.4 99.7 99.8 99.6 99.5 99.6 99.7 99.8 100.0 100.0
[14:01:38.492] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.6 99.5 99.5 99.4 99.0 99.7 99.7 99.6 99.5 99.6 99.7 99.8 100.0 100.0
[14:01:38.492] INFO: X-ray hit rate [MHz/cm2]: 57.0 37.7 55.4 89.2 92.0 95.7 93.0 65.6 64.3 82.4 86.2 78.8 79.3 46.6 19.6 20.6
[14:01:38.492] INFO: PixTestHighRate::doXPixelAlive() done
[14:01:38.537] INFO: PixTest:: pg_setup set to default.
[14:01:38.558] INFO: enter test to run
[14:01:49.767] INFO: test: exit no parameter change
[14:01:50.097] QUIET: Connection to board 32 closed.
[14:01:50.097] INFO: pXar: this is the end, my friend