[14:21:19.975] INFO: *** Welcome to pxar ***
[14:21:19.976] INFO: *** Today: 2016/05/12
[14:21:19.997] INFO: *** Version: v1.9.0-796-gef167-dirty
[14:21:19.997] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//dacParameters35_C15.dat
[14:21:20.034] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:21:20.034] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//defaultMaskFile.dat
[14:21:20.039] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C15.dat
[14:21:20.298] INFO: clk: 4
[14:21:20.298] INFO: ctr: 4
[14:21:20.298] INFO: sda: 19
[14:21:20.298] INFO: tin: 9
[14:21:20.298] INFO: level: 15
[14:21:20.298] INFO: triggerdelay: 0
[14:21:20.298] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[14:21:20.298] INFO: Log level: INFO
[14:21:20.313] QUIET: Connection to board DTB_WREKRL opened.
[14:21:20.317] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[14:21:20.319] INFO: RPC call hashes of host and DTB match: 398089610
[14:21:21.855] INFO: DUT info:
[14:21:21.856] INFO: The DUT currently contains the following objects:
[14:21:21.856] INFO: 2 TBM Cores tbm08c (2 ON)
[14:21:21.856] INFO: TBM Core alpha (0): 7 registers set
[14:21:21.856] INFO: TBM Core beta (1): 7 registers set
[14:21:21.856] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:21:21.856] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:21.856] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:21.856] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:21.856] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:21.856] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:21.856] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:21.856] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:21.856] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:21.856] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:21.857] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:21.857] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:21.857] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:21.857] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:21.857] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:21.857] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:21.857] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:21:22.261] INFO: enter 'restricted' command line mode
[14:21:22.261] INFO: enter test to run
[14:21:34.585] INFO: test: PixelAlive no parameter change
[14:21:34.585] INFO: running: pixelalive
[14:21:34.594] INFO: ----------------------------------------------------------------------
[14:21:34.594] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:21:34.594] INFO: ----------------------------------------------------------------------
[14:21:34.912] INFO: Expecting 41600 events.
[14:21:39.245] INFO: 41600 events read in total (3614ms).
[14:21:39.413] INFO: Test took 4816ms.
[14:21:39.425] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:39.686] INFO: PixTestAlive::aliveTest() done
[14:21:39.686] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 0 0 0 0 0 0 0 0 0
[14:21:39.719] INFO: enter test to run
[14:22:21.089] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:22:21.089] INFO: running: highrate
[14:22:21.090] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:22:21.409] INFO: ----------------------------------------------------------------------
[14:22:21.409] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:22:21.409] INFO: ----------------------------------------------------------------------
[14:22:21.409] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:22:21.409] INFO: edge/corner pixel THR is adjusted
[14:22:21.409] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:22:22.367] INFO: Collecting data for 5 seconds...
[14:22:27.387] INFO: Done with hot pixel readout
[14:22:39.938] INFO: PixTest:: pg_setup set to default.
[14:22:39.939] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:22:39.939] INFO: 44 hot pixels found in step 0
[14:22:40.938] INFO: Collecting data for 5 seconds...
[14:22:45.961] INFO: Done with hot pixel readout
[14:22:58.678] INFO: PixTest:: pg_setup set to default.
[14:22:58.679] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:22:58.679] INFO: 39 hot pixels found in step 1
[14:22:59.675] INFO: Collecting data for 5 seconds...
[14:23:04.693] INFO: Done with hot pixel readout
[14:23:17.451] INFO: PixTest:: pg_setup set to default.
[14:23:17.451] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:23:17.452] INFO: 39 hot pixels found in step 2
[14:23:18.452] INFO: Collecting data for 5 seconds...
[14:23:23.471] INFO: Done with hot pixel readout
[14:23:36.071] INFO: PixTest:: pg_setup set to default.
[14:23:36.071] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:23:36.071] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:23:36.072] INFO: 41 hot pixels found in step 3
[14:23:37.071] INFO: Collecting data for 5 seconds...
[14:23:42.088] INFO: Done with hot pixel readout
[14:23:54.649] INFO: PixTest:: pg_setup set to default.
[14:23:54.649] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:23:54.650] INFO: 30 hot pixels found in step 4
[14:23:55.650] INFO: Collecting data for 5 seconds...
[14:24:00.667] INFO: Done with hot pixel readout
[14:24:13.195] INFO: PixTest:: pg_setup set to default.
[14:24:13.195] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:24:13.196] INFO: 26 hot pixels found in step 5
[14:24:14.194] INFO: Collecting data for 5 seconds...
[14:24:19.211] INFO: Done with hot pixel readout
[14:24:31.533] INFO: PixTest:: pg_setup set to default.
[14:24:31.533] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:24:31.533] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:24:31.534] INFO: 27 hot pixels found in step 6
[14:24:32.533] INFO: Collecting data for 5 seconds...
[14:24:37.550] INFO: Done with hot pixel readout
[14:24:50.075] INFO: PixTest:: pg_setup set to default.
[14:24:50.075] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:24:50.076] INFO: 25 hot pixels found in step 7
[14:24:51.075] INFO: Collecting data for 5 seconds...
[14:24:56.092] INFO: Done with hot pixel readout
[14:25:08.629] INFO: PixTest:: pg_setup set to default.
[14:25:08.630] INFO: 19 hot pixels found in step 8
[14:25:09.629] INFO: Collecting data for 5 seconds...
[14:25:14.645] INFO: Done with hot pixel readout
[14:25:27.203] INFO: PixTest:: pg_setup set to default.
[14:25:27.203] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:25:27.204] INFO: 27 hot pixels found in step 9
[14:25:28.203] INFO: Collecting data for 5 seconds...
[14:25:33.219] INFO: Done with hot pixel readout
[14:25:45.750] INFO: PixTest:: pg_setup set to default.
[14:25:45.750] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:25:45.750] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:25:45.750] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:25:45.751] INFO: 20 hot pixels found in step 10
[14:25:46.749] INFO: Collecting data for 5 seconds...
[14:25:51.766] INFO: Done with hot pixel readout
[14:26:04.279] INFO: PixTest:: pg_setup set to default.
[14:26:04.279] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:26:04.279] INFO: 20 hot pixels found in step 11
[14:26:05.278] INFO: Collecting data for 5 seconds...
[14:26:10.294] INFO: Done with hot pixel readout
[14:26:22.835] INFO: PixTest:: pg_setup set to default.
[14:26:22.835] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:26:22.836] INFO: 17 hot pixels found in step 12
[14:26:23.835] INFO: Collecting data for 5 seconds...
[14:26:28.851] INFO: Done with hot pixel readout
[14:26:41.385] INFO: PixTest:: pg_setup set to default.
[14:26:41.385] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:26:41.385] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:26:41.385] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:26:41.386] INFO: 17 hot pixels found in step 13
[14:26:42.384] INFO: Collecting data for 5 seconds...
[14:26:47.401] INFO: Done with hot pixel readout
[14:26:59.901] INFO: PixTest:: pg_setup set to default.
[14:26:59.901] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:26:59.901] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:26:59.902] INFO: 15 hot pixels found in step 14
[14:26:59.944] INFO: 15 hot pixels could not be trimmed and have been masked.
[14:26:59.948] INFO: PixTest::trimHotPixels() done
[14:26:59.948] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C0.dat
[14:26:59.953] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C1.dat
[14:26:59.961] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C2.dat
[14:26:59.967] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C3.dat
[14:26:59.973] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C4.dat
[14:26:59.978] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C5.dat
[14:26:59.984] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C6.dat
[14:26:59.989] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C7.dat
[14:26:59.995] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C8.dat
[14:26:59.001] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C9.dat
[14:27:00.006] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C10.dat
[14:27:00.012] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C11.dat
[14:27:00.017] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C12.dat
[14:27:00.023] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C13.dat
[14:27:00.028] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C14.dat
[14:27:00.034] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C15.dat
[14:27:00.040] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//defaultMaskFile.dat
[14:27:00.050] INFO: enter test to run
[14:27:26.950] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:27:26.950] INFO: running: highrate
[14:27:26.955] INFO: ----------------------------------------------------------------------
[14:27:26.955] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:27:26.955] INFO: ----------------------------------------------------------------------
[14:27:26.955] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:27:26.955] INFO: edge/corner pixel THR is adjusted
[14:27:26.955] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:27:27.912] INFO: Collecting data for 1 seconds...
[14:27:28.916] INFO: Done with hot pixel readout
[14:27:33.394] INFO: PixTest:: pg_setup set to default.
[14:27:33.395] INFO: 0 hot pixels found in step 0
[14:27:33.400] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:27:33.475] INFO: PixTest::trimHotPixels() done
[14:27:33.475] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C0.dat
[14:27:33.484] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C1.dat
[14:27:33.489] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C2.dat
[14:27:33.495] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C3.dat
[14:27:33.500] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C4.dat
[14:27:33.506] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C5.dat
[14:27:33.511] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C6.dat
[14:27:33.517] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C7.dat
[14:27:33.522] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C8.dat
[14:27:33.528] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C9.dat
[14:27:33.533] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C10.dat
[14:27:33.539] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C11.dat
[14:27:33.544] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C12.dat
[14:27:33.550] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C13.dat
[14:27:33.555] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C14.dat
[14:27:33.561] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//trimParameters35_C15.dat
[14:27:33.566] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-D-NU_FPIXTest-17C-FNAL-160506-1238_2016-05-06_12h38m_1462556301/000_FPIXTest_p17//defaultMaskFile.dat
[14:27:33.576] INFO: enter test to run
[14:28:01.222] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:28:01.222] INFO: running: xray
[14:28:01.224] INFO: ----------------------------------------------------------------------
[14:28:01.224] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:28:01.224] INFO: ----------------------------------------------------------------------
[14:28:02.187] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:28:12.788] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[14:28:42.264] INFO: Resuming triggers.
[14:28:52.869] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[14:29:22.436] INFO: Resuming triggers.
[14:29:33.041] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[14:30:02.460] INFO: Resuming triggers.
[14:30:13.069] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[14:30:42.515] INFO: Resuming triggers.
[14:30:53.121] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[14:31:22.569] INFO: Resuming triggers.
[14:31:33.178] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[14:32:02.960] INFO: Resuming triggers.
[14:32:13.568] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[14:32:42.669] INFO: Resuming triggers.
[14:32:53.278] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[14:33:22.881] INFO: Resuming triggers.
[14:33:33.486] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[14:34:03.765] INFO: Resuming triggers.
[14:34:08.660] INFO: data taking finished, elapsed time: 100 seconds.
[14:34:22.955] INFO: PixTest:: pg_setup set to default.
[14:34:22.958] INFO: PixTestXray::doPhRun() done
[14:34:23.099] INFO: enter test to run
[14:35:13.043] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:35:13.043] INFO: running: xray
[14:35:13.044] INFO: ----------------------------------------------------------------------
[14:35:13.044] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:35:13.044] INFO: ----------------------------------------------------------------------
[14:35:14.007] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:35:19.801] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[14:35:49.980] INFO: Resuming triggers.
[14:35:55.771] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:36:25.851] INFO: Resuming triggers.
[14:36:31.648] INFO: run duration 17 seconds, buffer almost full (81%), pausing triggers.
[14:37:01.784] INFO: Resuming triggers.
[14:37:07.573] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:37:38.083] INFO: Resuming triggers.
[14:37:43.878] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[14:38:13.390] INFO: Resuming triggers.
[14:38:19.180] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:38:49.186] INFO: Resuming triggers.
[14:38:54.975] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[14:39:24.408] INFO: Resuming triggers.
[14:39:30.200] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:40:00.318] INFO: Resuming triggers.
[14:40:06.111] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[14:40:36.317] INFO: Resuming triggers.
[14:40:42.111] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[14:41:12.204] INFO: Resuming triggers.
[14:41:17.995] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[14:41:48.132] INFO: Resuming triggers.
[14:41:53.928] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:42:24.138] INFO: Resuming triggers.
[14:42:29.937] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[14:43:00.931] INFO: Resuming triggers.
[14:43:06.730] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[14:43:37.297] INFO: Resuming triggers.
[14:43:43.094] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[14:44:14.175] INFO: Resuming triggers.
[14:44:19.971] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[14:44:51.101] INFO: Resuming triggers.
[14:44:56.898] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[14:45:27.781] INFO: Resuming triggers.
[14:45:29.632] INFO: data taking finished, elapsed time: 100 seconds.
[14:45:39.569] INFO: PixTest:: pg_setup set to default.
[14:45:39.572] INFO: PixTestXray::doPhRun() done
[14:45:39.723] INFO: enter test to run
[14:46:14.902] INFO: test: HighRate no parameter change
[14:46:14.902] INFO: running: highrate
[14:46:14.903] INFO: ----------------------------------------------------------------------
[14:46:14.903] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:46:14.903] INFO: ----------------------------------------------------------------------
[14:46:15.045] INFO: Expecting 768 events.
[14:46:16.179] INFO: 768 events read in total (418ms).
[14:46:16.180] INFO: Test took 1269ms.
[14:46:16.983] INFO: Expecting 41600 events.
[14:46:20.073] INFO: 41600 events read in total (2563ms).
[14:46:20.074] INFO: Test took 3886ms.
[14:46:20.113] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:20.832] INFO: Expecting 41600 events.
[14:46:24.090] INFO: 41600 events read in total (2731ms).
[14:46:24.091] INFO: Test took 3959ms.
[14:46:24.131] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:24.846] INFO: Expecting 41600 events.
[14:46:28.144] INFO: 41600 events read in total (2771ms).
[14:46:28.145] INFO: Test took 3995ms.
[14:46:28.185] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:28.896] INFO: Expecting 41600 events.
[14:46:32.190] INFO: 41600 events read in total (2767ms).
[14:46:32.191] INFO: Test took 3986ms.
[14:46:32.232] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:32.945] INFO: Expecting 41600 events.
[14:46:36.269] INFO: 41600 events read in total (2797ms).
[14:46:36.270] INFO: Test took 4018ms.
[14:46:36.310] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:37.019] INFO: Expecting 41600 events.
[14:46:40.348] INFO: 41600 events read in total (2802ms).
[14:46:40.349] INFO: Test took 4018ms.
[14:46:40.389] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:41.099] INFO: Expecting 41600 events.
[14:46:44.418] INFO: 41600 events read in total (2792ms).
[14:46:44.419] INFO: Test took 4008ms.
[14:46:44.459] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:45.172] INFO: Expecting 41600 events.
[14:46:48.474] INFO: 41600 events read in total (2775ms).
[14:46:48.475] INFO: Test took 3995ms.
[14:46:48.516] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:49.230] INFO: Expecting 41600 events.
[14:46:52.556] INFO: 41600 events read in total (2800ms).
[14:46:52.557] INFO: Test took 4020ms.
[14:46:52.599] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:53.310] INFO: Expecting 41600 events.
[14:46:56.633] INFO: 41600 events read in total (2796ms).
[14:46:56.634] INFO: Test took 4016ms.
[14:46:56.675] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:57.386] INFO: Expecting 41600 events.
[14:47:00.701] INFO: 41600 events read in total (2788ms).
[14:47:00.702] INFO: Test took 4006ms.
[14:47:00.743] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:01.453] INFO: Expecting 41600 events.
[14:47:04.780] INFO: 41600 events read in total (2800ms).
[14:47:04.781] INFO: Test took 4019ms.
[14:47:04.822] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:05.530] INFO: Expecting 41600 events.
[14:47:08.860] INFO: 41600 events read in total (2803ms).
[14:47:08.861] INFO: Test took 4019ms.
[14:47:08.902] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:09.612] INFO: Expecting 41600 events.
[14:47:12.931] INFO: 41600 events read in total (2792ms).
[14:47:12.932] INFO: Test took 4009ms.
[14:47:12.973] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:13.686] INFO: Expecting 41600 events.
[14:47:16.997] INFO: 41600 events read in total (2784ms).
[14:47:16.998] INFO: Test took 4006ms.
[14:47:17.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:17.752] INFO: Expecting 41600 events.
[14:47:21.067] INFO: 41600 events read in total (2788ms).
[14:47:21.068] INFO: Test took 4007ms.
[14:47:21.109] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:21.822] INFO: Expecting 41600 events.
[14:47:25.132] INFO: 41600 events read in total (2783ms).
[14:47:25.133] INFO: Test took 4003ms.
[14:47:25.174] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:25.886] INFO: Expecting 41600 events.
[14:47:29.211] INFO: 41600 events read in total (2799ms).
[14:47:29.212] INFO: Test took 4017ms.
[14:47:29.252] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:29.962] INFO: Expecting 41600 events.
[14:47:33.281] INFO: 41600 events read in total (2792ms).
[14:47:33.282] INFO: Test took 4009ms.
[14:47:33.323] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:34.035] INFO: Expecting 41600 events.
[14:47:37.260] INFO: 41600 events read in total (2698ms).
[14:47:37.261] INFO: Test took 3918ms.
[14:47:37.301] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:37.644] INFO: enter test to run
[14:47:46.229] INFO: test: HighRate no parameter change
[14:47:46.229] INFO: running: highrate
[14:47:46.231] INFO: ----------------------------------------------------------------------
[14:47:46.231] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:47:46.231] INFO: ----------------------------------------------------------------------
[14:47:46.843] INFO: Expecting 208000 events.
[14:47:59.099] INFO: 208000 events read in total (11729ms).
[14:47:59.103] INFO: Test took 12866ms.
[14:47:59.281] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:59.544] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 0 0 0 0 0 0 0 0 0
[14:47:59.544] INFO: number of red-efficiency pixels: 105 66 103 151 148 141 138 138 135 181 173 137 145 76 21 46
[14:47:59.544] INFO: number of X-ray hits detected: 78556 54079 77504 121208 126962 136143 122882 96767 104104 136673 128322 116405 122949 73171 30387 33660
[14:47:59.544] 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:47:59.544] INFO: number of Vcal hits detected: 207894 207932 207896 207843 207845 207858 207763 207861 207862 207814 207822 207857 207849 207920 207979 207951
[14:47:59.544] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[14:47:59.544] INFO: Vcal hit overall efficiency (%): 99.9 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[14:47:59.544] INFO: X-ray hit rate [MHz/cm2]: 23.0 15.9 22.7 35.5 37.2 39.9 36.0 28.4 30.5 40.1 37.6 34.1 36.0 21.4 8.9 9.9
[14:47:59.544] INFO: PixTestHighRate::doXPixelAlive() done
[14:47:59.588] INFO: PixTest:: pg_setup set to default.
[14:47:59.598] INFO: enter test to run
[14:48:20.405] INFO: test: HighRate no parameter change
[14:48:20.405] INFO: running: highrate
[14:48:20.406] INFO: ----------------------------------------------------------------------
[14:48:20.406] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:48:20.406] INFO: ----------------------------------------------------------------------
[14:48:21.021] INFO: Expecting 208000 events.
[14:48:35.543] INFO: 208000 events read in total (13996ms).
[14:48:35.549] INFO: Test took 15132ms.
[14:48:35.909] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:36.232] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 0 0 0 0 0 0 0 0 0
[14:48:36.232] INFO: number of red-efficiency pixels: 338 188 414 633 675 542 461 433 369 803 700 426 501 295 84 83
[14:48:36.232] INFO: number of X-ray hits detected: 163450 113217 162189 251417 262738 282636 256054 202581 216754 282641 266454 242784 254837 152478 63250 70254
[14:48:36.232] 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:48:36.232] INFO: number of Vcal hits detected: 207632 207799 207522 207273 207229 207396 207401 207506 207605 207074 207191 207541 207447 207679 207912 207916
[14:48:36.232] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.7 99.7 99.8 99.8 99.8 99.6 99.6 99.8 99.8 99.9 100.0 100.0
[14:48:36.232] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.7 99.6 99.7 99.7 99.8 99.8 99.6 99.6 99.8 99.7 99.8 100.0 100.0
[14:48:36.232] INFO: X-ray hit rate [MHz/cm2]: 47.9 33.2 47.5 73.7 77.0 82.8 75.1 59.4 63.5 82.8 78.1 71.2 74.7 44.7 18.5 20.6
[14:48:36.232] INFO: PixTestHighRate::doXPixelAlive() done
[14:48:36.279] INFO: PixTest:: pg_setup set to default.
[14:48:36.294] INFO: enter test to run
[14:49:23.597] INFO: test: HighRate no parameter change
[14:49:23.597] INFO: running: highrate
[14:49:23.598] INFO: ----------------------------------------------------------------------
[14:49:23.598] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:49:23.598] INFO: ----------------------------------------------------------------------
[14:49:24.209] INFO: Expecting 208000 events.
[14:49:41.336] INFO: 208000 events read in total (16600ms).
[14:49:41.345] INFO: Test took 17739ms.
[14:49:41.887] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:42.271] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 0 0 0 0 0 0 0 0 0
[14:49:42.271] INFO: number of red-efficiency pixels: 698 408 885 1433 1584 1242 1076 1037 826 1897 1584 1061 1178 650 146 139
[14:49:42.271] INFO: number of X-ray hits detected: 245612 171167 244847 377923 395672 425320 383999 303331 326333 425975 400294 363160 385154 229520 95385 106642
[14:49:42.271] 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:49:42.271] INFO: number of Vcal hits detected: 207140 207538 206666 206099 205781 206336 206590 206596 207006 205174 205786 206649 206438 207176 207847 207856
[14:49:42.271] INFO: Vcal hit fiducial efficiency (%): 99.6 99.8 99.4 99.2 99.0 99.3 99.4 99.4 99.6 98.8 99.0 99.4 99.3 99.6 99.9 99.9
[14:49:42.271] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.4 99.1 98.9 99.2 99.3 99.3 99.5 98.6 98.9 99.4 99.2 99.6 99.9 99.9
[14:49:42.271] INFO: X-ray hit rate [MHz/cm2]: 72.0 50.2 71.8 110.8 116.0 124.7 112.6 88.9 95.7 124.9 117.3 106.4 112.9 67.3 28.0 31.3
[14:49:42.271] INFO: PixTestHighRate::doXPixelAlive() done
[14:49:42.317] INFO: PixTest:: pg_setup set to default.
[14:49:42.332] INFO: enter test to run
[14:50:45.132] INFO: test: exit no parameter change
[14:50:45.415] QUIET: Connection to board 33 closed.
[14:50:45.417] INFO: pXar: this is the end, my friend