[10:28:23.084] INFO: *** Welcome to pxar ***
[10:28:23.084] INFO: *** Today: 2016/04/28
[10:28:27.324] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:28:27.325] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//dacParameters35_C15.dat
[10:28:27.724] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:28:27.725] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//defaultMaskFile.dat
[10:28:27.841] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C15.dat
[10:28:29.148] INFO: clk: 4
[10:28:29.148] INFO: ctr: 4
[10:28:29.148] INFO: sda: 19
[10:28:29.148] INFO: tin: 9
[10:28:29.148] INFO: level: 15
[10:28:29.148] INFO: triggerdelay: 0
[10:28:29.148] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:28:29.148] INFO: Log level: INFO
[10:28:29.188] QUIET: Connection to board DTB_WREKRL opened.
[10:28:29.192] 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:
------------------------------------------------------
[10:28:29.194] INFO: RPC call hashes of host and DTB match: 398089610
[10:28:30.729] INFO: DUT info:
[10:28:30.729] INFO: The DUT currently contains the following objects:
[10:28:30.729] INFO: 2 TBM Cores tbm08c (2 ON)
[10:28:30.729] INFO: TBM Core alpha (0): 7 registers set
[10:28:30.729] INFO: TBM Core beta (1): 7 registers set
[10:28:30.729] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:28:30.729] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:30.729] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:30.729] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:30.729] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:30.729] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:30.729] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:30.729] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:30.729] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:30.729] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:30.729] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:30.730] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:30.730] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:30.730] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:30.730] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:30.730] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:30.730] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:28:31.167] INFO: enter 'restricted' command line mode
[10:28:31.167] INFO: enter test to run
[10:28:39.369] INFO: test: PixelAlive no parameter change
[10:28:39.369] INFO: running: pixelalive
[10:28:39.416] INFO: ----------------------------------------------------------------------
[10:28:39.416] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:28:39.416] INFO: ----------------------------------------------------------------------
[10:28:39.731] INFO: Expecting 41600 events.
[10:28:44.060] INFO: 41600 events read in total (3610ms).
[10:28:44.228] INFO: Test took 4810ms.
[10:28:44.239] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:44.501] INFO: PixTestAlive::aliveTest() done
[10:28:44.501] INFO: number of dead pixels (per ROC): 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:28:44.533] INFO: enter test to run
[10:29:25.777] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:29:25.777] INFO: running: highrate
[10:29:25.777] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:29:27.616] INFO: ----------------------------------------------------------------------
[10:29:27.616] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:29:27.616] INFO: ----------------------------------------------------------------------
[10:29:27.616] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:29:27.616] INFO: edge/corner pixel THR is adjusted
[10:29:27.616] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:29:28.576] INFO: Collecting data for 5 seconds...
[10:29:33.592] INFO: Done with hot pixel readout
[10:29:45.032] INFO: PixTest:: pg_setup set to default.
[10:29:45.033] INFO: 5 hot pixels found in step 0
[10:29:46.023] INFO: Collecting data for 5 seconds...
[10:29:51.040] INFO: Done with hot pixel readout
[10:30:02.029] INFO: PixTest:: pg_setup set to default.
[10:30:02.030] INFO: 5 hot pixels found in step 1
[10:30:03.023] INFO: Collecting data for 5 seconds...
[10:30:08.065] INFO: Done with hot pixel readout
[10:30:18.406] INFO: PixTest:: pg_setup set to default.
[10:30:18.407] INFO: 9 hot pixels found in step 2
[10:30:19.399] INFO: Collecting data for 5 seconds...
[10:30:24.413] INFO: Done with hot pixel readout
[10:30:35.378] INFO: PixTest:: pg_setup set to default.
[10:30:35.378] INFO: 4 hot pixels found in step 3
[10:30:36.370] INFO: Collecting data for 5 seconds...
[10:30:41.386] INFO: Done with hot pixel readout
[10:30:52.885] INFO: PixTest:: pg_setup set to default.
[10:30:52.886] INFO: 3 hot pixels found in step 4
[10:30:53.879] INFO: Collecting data for 5 seconds...
[10:30:58.894] INFO: Done with hot pixel readout
[10:31:09.349] INFO: PixTest:: pg_setup set to default.
[10:31:09.349] INFO: 6 hot pixels found in step 5
[10:31:10.341] INFO: Collecting data for 5 seconds...
[10:31:15.358] INFO: Done with hot pixel readout
[10:31:25.941] INFO: PixTest:: pg_setup set to default.
[10:31:25.942] INFO: 2 hot pixels found in step 6
[10:31:26.935] INFO: Collecting data for 5 seconds...
[10:31:31.952] INFO: Done with hot pixel readout
[10:31:42.086] INFO: PixTest:: pg_setup set to default.
[10:31:42.087] INFO: 5 hot pixels found in step 7
[10:31:43.080] INFO: Collecting data for 5 seconds...
[10:31:48.097] INFO: Done with hot pixel readout
[10:31:59.719] INFO: PixTest:: pg_setup set to default.
[10:31:59.720] INFO: 2 hot pixels found in step 8
[10:32:00.713] INFO: Collecting data for 5 seconds...
[10:32:05.728] INFO: Done with hot pixel readout
[10:32:17.457] INFO: PixTest:: pg_setup set to default.
[10:32:17.458] INFO: 3 hot pixels found in step 9
[10:32:18.451] INFO: Collecting data for 5 seconds...
[10:32:23.468] INFO: Done with hot pixel readout
[10:32:35.180] INFO: PixTest:: pg_setup set to default.
[10:32:35.181] INFO: 1 hot pixels found in step 10
[10:32:36.174] INFO: Collecting data for 5 seconds...
[10:32:41.190] INFO: Done with hot pixel readout
[10:32:52.870] INFO: PixTest:: pg_setup set to default.
[10:32:52.871] INFO: 1 hot pixels found in step 11
[10:32:53.864] INFO: Collecting data for 5 seconds...
[10:32:58.880] INFO: Done with hot pixel readout
[10:33:10.664] INFO: PixTest:: pg_setup set to default.
[10:33:10.665] INFO: 2 hot pixels found in step 12
[10:33:11.657] INFO: Collecting data for 5 seconds...
[10:33:16.673] INFO: Done with hot pixel readout
[10:33:28.382] INFO: PixTest:: pg_setup set to default.
[10:33:28.383] INFO: 0 hot pixels found in step 13
[10:33:28.420] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:33:28.423] INFO: PixTest::trimHotPixels() done
[10:33:28.424] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C0.dat
[10:33:28.429] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C1.dat
[10:33:28.435] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C2.dat
[10:33:28.453] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C3.dat
[10:33:28.465] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C4.dat
[10:33:28.473] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C5.dat
[10:33:28.478] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C6.dat
[10:33:28.484] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C7.dat
[10:33:28.489] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C8.dat
[10:33:28.494] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C9.dat
[10:33:28.499] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C10.dat
[10:33:28.505] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C11.dat
[10:33:28.510] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C12.dat
[10:33:28.515] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C13.dat
[10:33:28.521] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C14.dat
[10:33:28.526] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C15.dat
[10:33:28.531] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//defaultMaskFile.dat
[10:33:28.544] INFO: enter test to run
[10:33:51.127] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:33:51.127] INFO: running: highrate
[10:33:51.131] INFO: ----------------------------------------------------------------------
[10:33:51.131] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:33:51.131] INFO: ----------------------------------------------------------------------
[10:33:51.131] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:33:51.132] INFO: edge/corner pixel THR is adjusted
[10:33:51.132] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:33:52.089] INFO: Collecting data for 1 seconds...
[10:33:53.092] INFO: Done with hot pixel readout
[10:33:57.102] INFO: PixTest:: pg_setup set to default.
[10:33:57.103] INFO: 0 hot pixels found in step 0
[10:33:57.108] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:33:57.199] INFO: PixTest::trimHotPixels() done
[10:33:57.199] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C0.dat
[10:33:57.210] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C1.dat
[10:33:57.216] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C2.dat
[10:33:57.221] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C3.dat
[10:33:57.226] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C4.dat
[10:33:57.232] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C5.dat
[10:33:57.237] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C6.dat
[10:33:57.242] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C7.dat
[10:33:57.247] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C8.dat
[10:33:57.252] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C9.dat
[10:33:57.258] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C10.dat
[10:33:57.263] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C11.dat
[10:33:57.268] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C12.dat
[10:33:57.273] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C13.dat
[10:33:57.279] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C14.dat
[10:33:57.284] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//trimParameters35_C15.dat
[10:33:57.289] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-1-22_FPIXTest-17C-FNAL-160427-1301_2016-04-27_13h02m_1461780121/000_FPIXTest_p17//defaultMaskFile.dat
[10:33:57.306] INFO: enter test to run
[10:34:25.311] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:34:25.311] INFO: running: xray
[10:34:25.312] INFO: ----------------------------------------------------------------------
[10:34:25.312] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:34:25.312] INFO: ----------------------------------------------------------------------
[10:34:26.276] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:34:37.865] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:35:07.233] INFO: Resuming triggers.
[10:35:18.818] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:35:48.053] INFO: Resuming triggers.
[10:35:59.642] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:36:28.946] INFO: Resuming triggers.
[10:36:40.532] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:37:09.862] INFO: Resuming triggers.
[10:37:21.451] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[10:37:50.746] INFO: Resuming triggers.
[10:38:02.336] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:38:31.742] INFO: Resuming triggers.
[10:38:43.332] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:39:12.729] INFO: Resuming triggers.
[10:39:24.317] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:39:53.565] INFO: Resuming triggers.
[10:40:01.186] INFO: data taking finished, elapsed time: 100 seconds.
[10:40:20.562] INFO: PixTest:: pg_setup set to default.
[10:40:20.565] INFO: PixTestXray::doPhRun() done
[10:40:20.702] INFO: enter test to run
[10:40:35.612] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:40:35.612] INFO: running: xray
[10:40:35.613] INFO: ----------------------------------------------------------------------
[10:40:35.613] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:40:35.613] INFO: ----------------------------------------------------------------------
[10:40:36.576] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:40:43.226] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:41:12.233] INFO: Resuming triggers.
[10:41:18.883] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:41:47.579] INFO: Resuming triggers.
[10:41:54.226] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[10:42:24.310] INFO: Resuming triggers.
[10:42:30.956] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[10:43:01.007] INFO: Resuming triggers.
[10:43:07.653] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:43:37.731] INFO: Resuming triggers.
[10:43:44.381] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[10:44:14.727] INFO: Resuming triggers.
[10:44:21.373] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:44:51.562] INFO: Resuming triggers.
[10:44:58.211] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[10:45:28.527] INFO: Resuming triggers.
[10:45:35.177] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[10:46:04.918] INFO: Resuming triggers.
[10:46:11.569] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[10:46:41.735] INFO: Resuming triggers.
[10:46:48.390] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[10:47:18.487] INFO: Resuming triggers.
[10:47:25.140] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[10:47:55.309] INFO: Resuming triggers.
[10:48:01.957] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[10:48:32.180] INFO: Resuming triggers.
[10:48:38.834] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:49:08.880] INFO: Resuming triggers.
[10:49:15.530] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[10:49:45.595] INFO: Resuming triggers.
[10:49:46.184] INFO: data taking finished, elapsed time: 100 seconds.
[10:49:49.232] INFO: PixTest:: pg_setup set to default.
[10:49:49.235] INFO: PixTestXray::doPhRun() done
[10:49:49.386] INFO: enter test to run
[10:49:59.864] INFO: test: HighRate no parameter change
[10:49:59.864] INFO: running: highrate
[10:49:59.865] INFO: ----------------------------------------------------------------------
[10:49:59.865] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:49:59.865] INFO: ----------------------------------------------------------------------
[10:49:59.005] INFO: Expecting 768 events.
[10:50:01.139] INFO: 768 events read in total (418ms).
[10:50:01.140] INFO: Test took 1269ms.
[10:50:01.943] INFO: Expecting 41600 events.
[10:50:05.028] INFO: 41600 events read in total (2558ms).
[10:50:05.029] INFO: Test took 3883ms.
[10:50:05.070] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:05.794] INFO: Expecting 41600 events.
[10:50:08.992] INFO: 41600 events read in total (2671ms).
[10:50:08.993] INFO: Test took 3905ms.
[10:50:09.027] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:09.753] INFO: Expecting 41600 events.
[10:50:12.977] INFO: 41600 events read in total (2697ms).
[10:50:12.978] INFO: Test took 3933ms.
[10:50:13.012] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:13.738] INFO: Expecting 41600 events.
[10:50:16.971] INFO: 41600 events read in total (2706ms).
[10:50:16.972] INFO: Test took 3941ms.
[10:50:16.005] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:17.730] INFO: Expecting 41600 events.
[10:50:20.968] INFO: 41600 events read in total (2711ms).
[10:50:20.969] INFO: Test took 3946ms.
[10:50:20.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:21.728] INFO: Expecting 41600 events.
[10:50:24.973] INFO: 41600 events read in total (2718ms).
[10:50:24.974] INFO: Test took 3953ms.
[10:50:25.008] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:25.730] INFO: Expecting 41600 events.
[10:50:28.959] INFO: 41600 events read in total (2702ms).
[10:50:28.959] INFO: Test took 3934ms.
[10:50:28.993] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:29.717] INFO: Expecting 41600 events.
[10:50:32.965] INFO: 41600 events read in total (2721ms).
[10:50:32.966] INFO: Test took 3957ms.
[10:50:32.000] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:33.723] INFO: Expecting 41600 events.
[10:50:36.974] INFO: 41600 events read in total (2724ms).
[10:50:36.975] INFO: Test took 3958ms.
[10:50:37.008] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:37.731] INFO: Expecting 41600 events.
[10:50:40.963] INFO: 41600 events read in total (2705ms).
[10:50:40.964] INFO: Test took 3939ms.
[10:50:40.997] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:41.724] INFO: Expecting 41600 events.
[10:50:44.954] INFO: 41600 events read in total (2703ms).
[10:50:44.955] INFO: Test took 3939ms.
[10:50:44.989] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:45.713] INFO: Expecting 41600 events.
[10:50:48.965] INFO: 41600 events read in total (2725ms).
[10:50:48.966] INFO: Test took 3959ms.
[10:50:48.999] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:49.726] INFO: Expecting 41600 events.
[10:50:52.960] INFO: 41600 events read in total (2707ms).
[10:50:52.961] INFO: Test took 3943ms.
[10:50:52.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:53.721] INFO: Expecting 41600 events.
[10:50:56.955] INFO: 41600 events read in total (2707ms).
[10:50:56.956] INFO: Test took 3944ms.
[10:50:56.990] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:57.714] INFO: Expecting 41600 events.
[10:51:00.952] INFO: 41600 events read in total (2711ms).
[10:51:00.953] INFO: Test took 3944ms.
[10:51:00.986] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:01.713] INFO: Expecting 41600 events.
[10:51:04.932] INFO: 41600 events read in total (2692ms).
[10:51:04.933] INFO: Test took 3928ms.
[10:51:04.967] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:05.689] INFO: Expecting 41600 events.
[10:51:08.910] INFO: 41600 events read in total (2694ms).
[10:51:08.911] INFO: Test took 3927ms.
[10:51:08.945] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:09.673] INFO: Expecting 41600 events.
[10:51:12.507] INFO: 41600 events read in total (2308ms).
[10:51:12.508] INFO: Test took 3545ms.
[10:51:12.541] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:13.270] INFO: Expecting 41600 events.
[10:51:16.349] INFO: 41600 events read in total (2553ms).
[10:51:16.350] INFO: Test took 3791ms.
[10:51:16.383] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:17.110] INFO: Expecting 41600 events.
[10:51:20.185] INFO: 41600 events read in total (2548ms).
[10:51:20.186] INFO: Test took 3784ms.
[10:51:20.218] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:20.584] INFO: enter test to run
[10:51:22.135] INFO: test: HighRate no parameter change
[10:51:22.135] INFO: running: highrate
[10:51:22.136] INFO: ----------------------------------------------------------------------
[10:51:22.136] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:51:22.136] INFO: ----------------------------------------------------------------------
[10:51:22.751] INFO: Expecting 208000 events.
[10:51:34.520] INFO: 208000 events read in total (11242ms).
[10:51:34.523] INFO: Test took 12380ms.
[10:51:34.667] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:34.917] INFO: number of dead pixels (per ROC): 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:51:34.917] INFO: number of red-efficiency pixels: 81 47 75 151 131 155 122 109 113 145 114 112 90 74 32 24
[10:51:34.918] INFO: number of X-ray hits detected: 65706 41758 63082 103133 105356 115050 113078 80960 80347 97618 91130 78551 84409 50432 21789 24897
[10:51:34.918] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:51:34.918] INFO: number of Vcal hits detected: 207919 207855 207924 207849 207866 207844 207876 207890 207886 207852 207884 207887 207904 207926 207968 207976
[10:51:34.918] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[10:51:34.918] INFO: Vcal hit overall efficiency (%): 100.0 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[10:51:34.918] INFO: X-ray hit rate [MHz/cm2]: 19.3 12.2 18.5 30.2 30.9 33.7 33.1 23.7 23.6 28.6 26.7 23.0 24.7 14.8 6.4 7.3
[10:51:34.918] INFO: PixTestHighRate::doXPixelAlive() done
[10:51:34.962] INFO: PixTest:: pg_setup set to default.
[10:51:34.969] INFO: enter test to run
[10:51:43.223] INFO: test: HighRate no parameter change
[10:51:43.223] INFO: running: highrate
[10:51:43.224] INFO: ----------------------------------------------------------------------
[10:51:43.224] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:51:43.224] INFO: ----------------------------------------------------------------------
[10:51:43.836] INFO: Expecting 208000 events.
[10:51:55.852] INFO: 208000 events read in total (11489ms).
[10:51:55.857] INFO: Test took 12625ms.
[10:51:56.149] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:56.445] INFO: number of dead pixels (per ROC): 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:51:56.445] INFO: number of red-efficiency pixels: 220 103 184 433 457 409 397 281 229 404 375 277 276 197 57 61
[10:51:56.445] INFO: number of X-ray hits detected: 133919 85250 127444 208959 214727 234234 229582 165614 162319 198893 185556 160269 172118 102758 43963 50640
[10:51:56.445] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:51:56.445] INFO: number of Vcal hits detected: 207764 207794 207801 207533 207512 207557 207576 207697 207764 207576 207592 207710 207713 207789 207941 207937
[10:51:56.445] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[10:51:56.445] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[10:51:56.445] INFO: X-ray hit rate [MHz/cm2]: 39.3 25.0 37.4 61.2 62.9 68.7 67.3 48.5 47.6 58.3 54.4 47.0 50.4 30.1 12.9 14.8
[10:51:56.445] INFO: PixTestHighRate::doXPixelAlive() done
[10:51:56.495] INFO: PixTest:: pg_setup set to default.
[10:51:56.507] INFO: enter test to run
[10:52:05.847] INFO: test: HighRate no parameter change
[10:52:05.847] INFO: running: highrate
[10:52:05.848] INFO: ----------------------------------------------------------------------
[10:52:05.848] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:52:05.848] INFO: ----------------------------------------------------------------------
[10:52:06.457] INFO: Expecting 208000 events.
[10:52:21.912] INFO: 208000 events read in total (14928ms).
[10:52:21.919] INFO: Test took 16065ms.
[10:52:22.359] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:22.705] INFO: number of dead pixels (per ROC): 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:52:22.705] INFO: number of red-efficiency pixels: 492 194 498 1125 1111 979 922 600 509 976 876 672 595 403 99 119
[10:52:22.705] INFO: number of X-ray hits detected: 208344 132482 199065 323869 334240 364192 358312 258544 254118 309292 289450 250401 268053 161084 68920 79075
[10:52:22.705] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:52:22.705] INFO: number of Vcal hits detected: 207396 207695 207417 206588 206587 206776 206889 207292 207440 206813 206974 207240 207335 207524 207897 207880
[10:52:22.705] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.4 99.4 99.5 99.5 99.7 99.7 99.5 99.5 99.7 99.7 99.8 100.0 99.9
[10:52:22.705] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.7 99.3 99.3 99.4 99.5 99.7 99.7 99.4 99.5 99.6 99.7 99.8 100.0 99.9
[10:52:22.705] INFO: X-ray hit rate [MHz/cm2]: 61.1 38.8 58.3 94.9 98.0 106.7 105.0 75.8 74.5 90.7 84.8 73.4 78.6 47.2 20.2 23.2
[10:52:22.705] INFO: PixTestHighRate::doXPixelAlive() done
[10:52:22.754] INFO: PixTest:: pg_setup set to default.
[10:52:22.772] INFO: enter test to run
[10:52:24.310] INFO: test: exit no parameter change
[10:52:24.668] QUIET: Connection to board 33 closed.
[10:52:24.669] INFO: pXar: this is the end, my friend