[11:48:37.502] INFO: *** Welcome to pxar ***
[11:48:37.502] INFO: *** Today: 2016/05/19
[11:48:37.520] INFO: *** Version: v1.9.0-796-gef167-dirty
[11:48:37.520] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//dacParameters35_C15.dat
[11:48:37.551] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:48:37.551] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//defaultMaskFile.dat
[11:48:37.554] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C15.dat
[11:48:37.639] INFO: clk: 4
[11:48:37.639] INFO: ctr: 4
[11:48:37.639] INFO: sda: 19
[11:48:37.639] INFO: tin: 9
[11:48:37.639] INFO: level: 15
[11:48:37.639] INFO: triggerdelay: 0
[11:48:37.639] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[11:48:37.639] INFO: Log level: INFO
[11:48:37.656] QUIET: Connection to board DTB_WREK4U opened.
[11:48:37.659] 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:
------------------------------------------------------
[11:48:37.662] INFO: RPC call hashes of host and DTB match: 398089610
[11:48:39.199] INFO: DUT info:
[11:48:39.199] INFO: The DUT currently contains the following objects:
[11:48:39.199] INFO: 2 TBM Cores tbm08c (2 ON)
[11:48:39.199] INFO: TBM Core alpha (0): 7 registers set
[11:48:39.199] INFO: TBM Core beta (1): 7 registers set
[11:48:39.199] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:48:39.199] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.199] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.199] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.199] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.199] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.199] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.199] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.199] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.199] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.199] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.199] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.199] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.199] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.200] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.200] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.200] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:48:39.618] INFO: enter 'restricted' command line mode
[11:48:39.618] INFO: enter test to run
[11:48:45.895] INFO: test: PixelAlive no parameter change
[11:48:45.895] INFO: running: pixelalive
[11:48:45.905] INFO: ----------------------------------------------------------------------
[11:48:45.905] 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:48:45.905] INFO: ----------------------------------------------------------------------
[11:48:46.236] INFO: Expecting 41600 events.
[11:48:50.658] INFO: 41600 events read in total (3703ms).
[11:48:50.824] INFO: Test took 4917ms.
[11:48:50.836] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:48:51.097] INFO: PixTestAlive::aliveTest() done
[11:48:51.098] INFO: number of dead pixels (per ROC): 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0
[11:48:51.131] INFO: enter test to run
[11:49:15.607] INFO: test: \HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[11:49:15.607] INFO: running: \highrate
[11:49:15.607] INFO: command ->\highrate<- not known, ignored
[11:49:15.608] INFO: enter test to run
[11:49:37.871] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[11:49:37.871] INFO: running: highrate
[11:49:37.872] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[11:49:38.154] INFO: ----------------------------------------------------------------------
[11:49:38.154] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[11:49:38.154] INFO: ----------------------------------------------------------------------
[11:49:38.154] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[11:49:38.154] INFO: edge/corner pixel THR is adjusted
[11:49:38.154] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:49:39.113] INFO: Collecting data for 5 seconds...
[11:49:44.129] INFO: Done with hot pixel readout
[11:49:55.304] INFO: PixTest:: pg_setup set to default.
[11:49:55.305] INFO: 0 hot pixels found in step 0
[11:49:55.319] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:49:55.335] INFO: PixTest::trimHotPixels() done
[11:49:55.374] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C0.dat
[11:49:55.379] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C1.dat
[11:49:55.385] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C2.dat
[11:49:55.390] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C3.dat
[11:49:55.396] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C4.dat
[11:49:55.401] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C5.dat
[11:49:55.407] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C6.dat
[11:49:55.412] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C7.dat
[11:49:55.417] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C8.dat
[11:49:55.423] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C9.dat
[11:49:55.429] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C10.dat
[11:49:55.434] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C11.dat
[11:49:55.440] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C12.dat
[11:49:55.445] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C13.dat
[11:49:55.450] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C14.dat
[11:49:55.456] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C15.dat
[11:49:55.461] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//defaultMaskFile.dat
[11:49:55.472] INFO: enter test to run
[11:51:39.702] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[11:51:39.702] INFO: running: highrate
[11:51:39.706] INFO: ----------------------------------------------------------------------
[11:51:39.706] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:51:39.706] INFO: ----------------------------------------------------------------------
[11:51:39.706] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:51:39.707] INFO: edge/corner pixel THR is adjusted
[11:51:39.707] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:51:40.664] INFO: Collecting data for 1 seconds...
[11:51:41.668] INFO: Done with hot pixel readout
[11:51:45.557] INFO: PixTest:: pg_setup set to default.
[11:51:45.558] INFO: 0 hot pixels found in step 0
[11:51:45.564] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:51:45.662] INFO: PixTest::trimHotPixels() done
[11:51:45.662] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C0.dat
[11:51:45.669] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C1.dat
[11:51:45.677] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C2.dat
[11:51:45.682] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C3.dat
[11:51:45.688] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C4.dat
[11:51:45.693] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C5.dat
[11:51:45.698] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C6.dat
[11:51:45.704] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C7.dat
[11:51:45.709] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C8.dat
[11:51:45.714] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C9.dat
[11:51:45.720] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C10.dat
[11:51:45.725] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C11.dat
[11:51:45.730] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C12.dat
[11:51:45.736] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C13.dat
[11:51:45.741] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C14.dat
[11:51:45.746] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//trimParameters35_C15.dat
[11:51:45.752] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-1-35_FPIXTest-17C-FNAL-160513-1252_2016-05-13_12h52m_1463161948/000_FPIXTest_p17//defaultMaskFile.dat
[11:51:45.765] INFO: enter test to run
[11:52:06.790] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:52:06.790] INFO: running: xray
[11:52:06.791] INFO: ----------------------------------------------------------------------
[11:52:06.791] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:52:06.791] INFO: ----------------------------------------------------------------------
[11:52:07.755] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:52:19.732] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:52:48.958] INFO: Resuming triggers.
[11:53:00.942] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:53:29.978] INFO: Resuming triggers.
[11:53:41.955] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[11:54:08.785] INFO: Resuming triggers.
[11:54:20.763] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[11:54:46.849] INFO: Resuming triggers.
[11:54:58.825] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[11:55:25.003] INFO: Resuming triggers.
[11:55:36.986] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[11:56:03.802] INFO: Resuming triggers.
[11:56:15.785] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[11:56:41.177] INFO: Resuming triggers.
[11:56:53.157] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[11:57:19.504] INFO: Resuming triggers.
[11:57:23.988] INFO: data taking finished, elapsed time: 100 seconds.
[11:57:33.850] INFO: PixTest:: pg_setup set to default.
[11:57:33.853] INFO: PixTestXray::doPhRun() done
[11:57:34.044] INFO: enter test to run
[11:57:53.803] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:57:53.804] INFO: running: xray
[11:57:53.804] INFO: ----------------------------------------------------------------------
[11:57:53.804] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:57:53.804] INFO: ----------------------------------------------------------------------
[11:57:54.767] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:58:01.701] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[11:58:30.795] INFO: Resuming triggers.
[11:58:37.732] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[11:59:06.481] INFO: Resuming triggers.
[11:59:13.420] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[11:59:43.397] INFO: Resuming triggers.
[11:59:50.334] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[12:00:20.272] INFO: Resuming triggers.
[12:00:27.211] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:00:56.562] INFO: Resuming triggers.
[12:01:03.498] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[12:01:34.203] INFO: Resuming triggers.
[12:01:41.140] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[12:02:11.040] INFO: Resuming triggers.
[12:02:17.974] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[12:02:46.834] INFO: Resuming triggers.
[12:02:53.768] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[12:03:24.870] INFO: Resuming triggers.
[12:03:31.807] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[12:04:01.472] INFO: Resuming triggers.
[12:04:08.406] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[12:04:38.404] INFO: Resuming triggers.
[12:04:45.338] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[12:05:15.411] INFO: Resuming triggers.
[12:05:22.344] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[12:05:52.409] INFO: Resuming triggers.
[12:05:59.343] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[12:06:29.506] INFO: Resuming triggers.
[12:06:32.752] INFO: data taking finished, elapsed time: 100 seconds.
[12:06:47.162] INFO: PixTest:: pg_setup set to default.
[12:06:47.165] INFO: PixTestXray::doPhRun() done
[12:06:47.313] INFO: enter test to run
[12:09:49.054] INFO: test: HighRate no parameter change
[12:09:49.054] INFO: running: highrate
[12:09:49.076] INFO: ----------------------------------------------------------------------
[12:09:49.076] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:09:49.076] INFO: ----------------------------------------------------------------------
[12:09:49.240] INFO: Expecting 768 events.
[12:09:50.373] INFO: 768 events read in total (418ms).
[12:09:50.374] INFO: Test took 1269ms.
[12:09:51.177] INFO: Expecting 41600 events.
[12:09:54.208] INFO: 41600 events read in total (2504ms).
[12:09:54.210] INFO: Test took 3829ms.
[12:09:54.240] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:54.974] INFO: Expecting 41600 events.
[12:09:58.110] INFO: 41600 events read in total (2609ms).
[12:09:58.111] INFO: Test took 3856ms.
[12:09:58.142] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:58.871] INFO: Expecting 41600 events.
[12:10:02.097] INFO: 41600 events read in total (2699ms).
[12:10:02.098] INFO: Test took 3939ms.
[12:10:02.129] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:02.859] INFO: Expecting 41600 events.
[12:10:06.090] INFO: 41600 events read in total (2705ms).
[12:10:06.091] INFO: Test took 3943ms.
[12:10:06.123] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:06.847] INFO: Expecting 41600 events.
[12:10:10.064] INFO: 41600 events read in total (2691ms).
[12:10:10.065] INFO: Test took 3925ms.
[12:10:10.097] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:10.821] INFO: Expecting 41600 events.
[12:10:14.055] INFO: 41600 events read in total (2707ms).
[12:10:14.056] INFO: Test took 3942ms.
[12:10:14.087] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:14.805] INFO: Expecting 41600 events.
[12:10:18.033] INFO: 41600 events read in total (2701ms).
[12:10:18.034] INFO: Test took 3930ms.
[12:10:18.066] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:18.793] INFO: Expecting 41600 events.
[12:10:22.066] INFO: 41600 events read in total (2746ms).
[12:10:22.067] INFO: Test took 3985ms.
[12:10:22.099] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:22.826] INFO: Expecting 41600 events.
[12:10:26.072] INFO: 41600 events read in total (2719ms).
[12:10:26.073] INFO: Test took 3958ms.
[12:10:26.104] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:26.829] INFO: Expecting 41600 events.
[12:10:30.079] INFO: 41600 events read in total (2723ms).
[12:10:30.080] INFO: Test took 3958ms.
[12:10:30.111] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:30.837] INFO: Expecting 41600 events.
[12:10:34.018] INFO: 41600 events read in total (2654ms).
[12:10:34.018] INFO: Test took 3890ms.
[12:10:34.050] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:34.780] INFO: Expecting 41600 events.
[12:10:38.015] INFO: 41600 events read in total (2708ms).
[12:10:38.017] INFO: Test took 3951ms.
[12:10:38.055] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:38.763] INFO: Expecting 41600 events.
[12:10:42.009] INFO: 41600 events read in total (2719ms).
[12:10:42.010] INFO: Test took 3938ms.
[12:10:42.042] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:42.770] INFO: Expecting 41600 events.
[12:10:45.981] INFO: 41600 events read in total (2684ms).
[12:10:45.982] INFO: Test took 3924ms.
[12:10:46.014] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:46.742] INFO: Expecting 41600 events.
[12:10:49.946] INFO: 41600 events read in total (2678ms).
[12:10:49.947] INFO: Test took 3916ms.
[12:10:49.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:50.701] INFO: Expecting 41600 events.
[12:10:53.875] INFO: 41600 events read in total (2647ms).
[12:10:53.876] INFO: Test took 3878ms.
[12:10:53.908] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:54.638] INFO: Expecting 41600 events.
[12:10:57.832] INFO: 41600 events read in total (2667ms).
[12:10:57.833] INFO: Test took 3908ms.
[12:10:57.865] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:58.597] INFO: Expecting 41600 events.
[12:11:01.723] INFO: 41600 events read in total (2600ms).
[12:11:01.724] INFO: Test took 3842ms.
[12:11:01.755] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:02.480] INFO: Expecting 41600 events.
[12:11:05.412] INFO: 41600 events read in total (2405ms).
[12:11:05.413] INFO: Test took 3641ms.
[12:11:05.445] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:06.175] INFO: Expecting 41600 events.
[12:11:09.287] INFO: 41600 events read in total (2585ms).
[12:11:09.288] INFO: Test took 3825ms.
[12:11:09.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:09.684] INFO: enter test to run
[12:11:23.645] INFO: test: HighRate no parameter change
[12:11:23.645] INFO: running: highrate
[12:11:23.646] INFO: ----------------------------------------------------------------------
[12:11:23.646] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:11:23.646] INFO: ----------------------------------------------------------------------
[12:11:24.259] INFO: Expecting 208000 events.
[12:11:35.366] INFO: 208000 events read in total (10580ms).
[12:11:35.369] INFO: Test took 11716ms.
[12:11:35.503] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:11:35.751] INFO: number of dead pixels (per ROC): 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0
[12:11:35.751] INFO: number of red-efficiency pixels: 54 37 49 102 114 129 113 125 95 119 93 102 78 59 18 30
[12:11:35.751] INFO: number of X-ray hits detected: 54529 37494 55737 93490 96731 98693 95943 78945 76708 88395 86707 75109 77118 45952 19444 21936
[12:11:35.751] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:11:35.751] INFO: number of Vcal hits detected: 207944 207963 207951 207895 207787 207864 207885 207874 207904 207880 207905 207896 207919 207941 207982 207970
[12:11:35.751] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0
[12:11:35.751] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0
[12:11:35.751] INFO: X-ray hit rate [MHz/cm2]: 16.0 11.0 16.3 27.4 28.4 28.9 28.1 23.1 22.5 25.9 25.4 22.0 22.6 13.5 5.7 6.4
[12:11:35.751] INFO: PixTestHighRate::doXPixelAlive() done
[12:11:35.796] INFO: PixTest:: pg_setup set to default.
[12:11:35.806] INFO: enter test to run
[12:12:21.581] INFO: test: HighRate no parameter change
[12:12:21.581] INFO: running: highrate
[12:12:21.582] INFO: ----------------------------------------------------------------------
[12:12:21.582] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:12:21.582] INFO: ----------------------------------------------------------------------
[12:12:22.200] INFO: Expecting 208000 events.
[12:12:34.870] INFO: 208000 events read in total (12143ms).
[12:12:34.875] INFO: Test took 13284ms.
[12:12:35.167] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:12:35.466] INFO: number of dead pixels (per ROC): 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0
[12:12:35.466] INFO: number of red-efficiency pixels: 153 90 238 336 491 498 411 357 286 328 362 319 341 181 57 59
[12:12:35.466] INFO: number of X-ray hits detected: 123074 84826 125871 211136 219454 223198 216972 179089 173281 200507 196270 170098 174623 104123 43760 49406
[12:12:35.466] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:12:35.466] INFO: number of Vcal hits detected: 207841 207907 207751 207647 207373 207448 207554 207613 207702 207654 207615 207664 207639 207808 207943 207940
[12:12:35.467] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[12:12:35.467] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.7 99.7 99.8 99.8 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[12:12:35.467] INFO: X-ray hit rate [MHz/cm2]: 36.1 24.9 36.9 61.9 64.3 65.4 63.6 52.5 50.8 58.8 57.5 49.9 51.2 30.5 12.8 14.5
[12:12:35.467] INFO: PixTestHighRate::doXPixelAlive() done
[12:12:35.512] INFO: PixTest:: pg_setup set to default.
[12:12:35.525] INFO: enter test to run
[12:12:53.285] INFO: test: HighRate no parameter change
[12:12:53.285] INFO: running: highrate
[12:12:53.286] INFO: ----------------------------------------------------------------------
[12:12:53.286] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:12:53.286] INFO: ----------------------------------------------------------------------
[12:12:53.904] INFO: Expecting 208000 events.
[12:13:07.740] INFO: 208000 events read in total (13309ms).
[12:13:07.749] INFO: Test took 14455ms.
[12:13:08.183] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:08.532] INFO: number of dead pixels (per ROC): 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0
[12:13:08.532] INFO: number of red-efficiency pixels: 364 195 483 776 1102 1146 881 776 648 804 795 686 709 367 89 90
[12:13:08.532] INFO: number of X-ray hits detected: 183838 125725 187589 314062 325114 330595 322296 265116 257519 297398 292270 252046 259466 155416 66017 73212
[12:13:08.532] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:13:08.532] INFO: number of Vcal hits detected: 207582 207794 207432 207101 206548 206556 206922 207029 207243 207041 207058 207198 207196 207594 207906 207907
[12:13:08.532] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.6 99.4 99.4 99.5 99.6 99.7 99.6 99.6 99.7 99.6 99.8 100.0 100.0
[12:13:08.532] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.3 99.3 99.5 99.5 99.6 99.5 99.5 99.6 99.6 99.8 100.0 100.0
[12:13:08.532] INFO: X-ray hit rate [MHz/cm2]: 53.9 36.9 55.0 92.1 95.3 96.9 94.5 77.7 75.5 87.2 85.7 73.9 76.1 45.6 19.4 21.5
[12:13:08.532] INFO: PixTestHighRate::doXPixelAlive() done
[12:13:08.578] INFO: PixTest:: pg_setup set to default.
[12:13:08.602] INFO: enter test to run
[12:13:17.284] INFO: test: exit no parameter change
[12:13:17.849] QUIET: Connection to board 32 closed.
[12:13:17.881] INFO: pXar: this is the end, my friend