[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