[14:57:11.874] INFO: *** Welcome to pxar ***
[14:57:11.874] INFO: *** Today: 2016/05/04
[14:57:11.891] INFO: *** Version: v1.9.0-796-gef167-dirty
[14:57:11.891] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//dacParameters35_C15.dat
[14:57:11.919] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:57:11.919] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//defaultMaskFile.dat
[14:57:11.922] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C15.dat
[14:57:12.014] INFO: clk: 4
[14:57:12.014] INFO: ctr: 4
[14:57:12.014] INFO: sda: 19
[14:57:12.014] INFO: tin: 9
[14:57:12.014] INFO: level: 15
[14:57:12.014] INFO: triggerdelay: 0
[14:57:12.014] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[14:57:12.014] INFO: Log level: INFO
[14:57:12.030] QUIET: Connection to board DTB_WREK4U opened.
[14:57:12.033] 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:
------------------------------------------------------
[14:57:12.036] INFO: RPC call hashes of host and DTB match: 398089610
[14:57:13.565] INFO: DUT info:
[14:57:13.565] INFO: The DUT currently contains the following objects:
[14:57:13.565] INFO: 2 TBM Cores tbm08c (2 ON)
[14:57:13.565] INFO: TBM Core alpha (0): 7 registers set
[14:57:13.565] INFO: TBM Core beta (1): 7 registers set
[14:57:13.565] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:57:13.565] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.565] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.566] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.566] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.566] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.566] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.566] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.566] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.566] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.566] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.566] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.566] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.566] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.566] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.566] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.566] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:13.970] INFO: enter 'restricted' command line mode
[14:57:13.970] INFO: enter test to run
[14:58:21.198] INFO: test: PixelAlive no parameter change
[14:58:21.198] INFO: running: pixelalive
[14:58:21.208] INFO: ----------------------------------------------------------------------
[14:58:21.208] 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:58:21.208] INFO: ----------------------------------------------------------------------
[14:58:21.524] INFO: Expecting 41600 events.
[14:58:25.850] INFO: 41600 events read in total (3608ms).
[14:58:26.018] INFO: Test took 4808ms.
[14:58:26.031] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:26.292] INFO: PixTestAlive::aliveTest() done
[14:58:26.292] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[14:58:26.326] INFO: enter test to run
[14:58:50.462] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:58:50.462] INFO: running: highrate
[14:58:50.463] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:58:50.691] INFO: ----------------------------------------------------------------------
[14:58:50.691] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:58:50.691] INFO: ----------------------------------------------------------------------
[14:58:50.691] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:58:50.692] INFO: edge/corner pixel THR is adjusted
[14:58:50.692] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:58:51.650] INFO: Collecting data for 5 seconds...
[14:58:56.667] INFO: Done with hot pixel readout
[14:59:07.529] INFO: PixTest:: pg_setup set to default.
[14:59:07.530] INFO: 0 hot pixels found in step 0
[14:59:07.542] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:59:07.581] INFO: PixTest::trimHotPixels() done
[14:59:07.581] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C0.dat
[14:59:07.586] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C1.dat
[14:59:07.593] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C2.dat
[14:59:07.599] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C3.dat
[14:59:07.604] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C4.dat
[14:59:07.609] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C5.dat
[14:59:07.614] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C6.dat
[14:59:07.620] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C7.dat
[14:59:07.625] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C8.dat
[14:59:07.630] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C9.dat
[14:59:07.636] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C10.dat
[14:59:07.641] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C11.dat
[14:59:07.646] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C12.dat
[14:59:07.652] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C13.dat
[14:59:07.657] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C14.dat
[14:59:07.662] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C15.dat
[14:59:07.668] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//defaultMaskFile.dat
[14:59:07.677] INFO: enter test to run
[14:59:45.261] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:59:45.262] INFO: running: highrate
[14:59:45.266] INFO: ----------------------------------------------------------------------
[14:59:45.266] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:59:45.266] INFO: ----------------------------------------------------------------------
[14:59:45.266] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:59:45.266] INFO: edge/corner pixel THR is adjusted
[14:59:45.266] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:59:46.223] INFO: Collecting data for 1 seconds...
[14:59:47.227] INFO: Done with hot pixel readout
[14:59:50.929] INFO: PixTest:: pg_setup set to default.
[14:59:50.930] INFO: 0 hot pixels found in step 0
[14:59:50.936] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:59:51.038] INFO: PixTest::trimHotPixels() done
[14:59:51.038] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C0.dat
[14:59:51.050] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C1.dat
[14:59:51.055] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C2.dat
[14:59:51.060] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C3.dat
[14:59:51.066] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C4.dat
[14:59:51.071] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C5.dat
[14:59:51.076] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C6.dat
[14:59:51.081] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C7.dat
[14:59:51.087] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C8.dat
[14:59:51.092] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C9.dat
[14:59:51.097] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C10.dat
[14:59:51.102] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C11.dat
[14:59:51.108] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C12.dat
[14:59:51.113] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C13.dat
[14:59:51.118] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C14.dat
[14:59:51.123] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C15.dat
[14:59:51.129] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-36_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//defaultMaskFile.dat
[14:59:51.138] INFO: enter test to run
[15:00:27.997] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[15:00:27.997] INFO: running: xray
[15:00:27.999] INFO: ----------------------------------------------------------------------
[15:00:27.999] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:00:27.999] INFO: ----------------------------------------------------------------------
[15:00:28.962] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:00:41.062] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[15:01:10.343] INFO: Resuming triggers.
[15:01:22.442] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[15:01:51.885] INFO: Resuming triggers.
[15:02:03.983] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[15:02:33.147] INFO: Resuming triggers.
[15:02:45.247] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[15:03:14.353] INFO: Resuming triggers.
[15:03:26.451] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[15:03:55.691] INFO: Resuming triggers.
[15:04:07.792] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[15:04:37.017] INFO: Resuming triggers.
[15:04:49.117] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[15:05:18.388] INFO: Resuming triggers.
[15:05:30.485] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[15:05:59.757] INFO: Resuming triggers.
[15:06:03.297] INFO: data taking finished, elapsed time: 100 seconds.
[15:06:12.171] INFO: PixTest:: pg_setup set to default.
[15:06:12.174] INFO: PixTestXray::doPhRun() done
[15:06:12.308] INFO: enter test to run
[15:08:57.082] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:08:57.082] INFO: running: xray
[15:08:57.083] INFO: ----------------------------------------------------------------------
[15:08:57.083] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:08:57.083] INFO: ----------------------------------------------------------------------
[15:08:58.046] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:09:05.396] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:09:35.458] INFO: Resuming triggers.
[15:09:42.808] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[15:10:13.403] INFO: Resuming triggers.
[15:10:20.753] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[15:10:50.150] INFO: Resuming triggers.
[15:10:57.500] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[15:11:27.537] INFO: Resuming triggers.
[15:11:34.889] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[15:12:04.001] INFO: Resuming triggers.
[15:12:12.351] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[15:12:42.424] INFO: Resuming triggers.
[15:12:49.773] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[15:13:19.818] INFO: Resuming triggers.
[15:13:27.170] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[15:13:57.303] INFO: Resuming triggers.
[15:14:04.653] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[15:14:34.896] INFO: Resuming triggers.
[15:14:42.246] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[15:15:12.352] INFO: Resuming triggers.
[15:15:19.703] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[15:15:49.750] INFO: Resuming triggers.
[15:15:57.102] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[15:16:27.210] INFO: Resuming triggers.
[15:16:34.562] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[15:17:04.693] INFO: Resuming triggers.
[15:17:09.470] INFO: data taking finished, elapsed time: 100 seconds.
[15:17:29.512] INFO: PixTest:: pg_setup set to default.
[15:17:29.515] INFO: PixTestXray::doPhRun() done
[15:17:29.665] INFO: enter test to run
[15:18:57.381] INFO: test: HighRate no parameter change
[15:18:57.381] INFO: running: highrate
[15:18:57.382] INFO: ----------------------------------------------------------------------
[15:18:57.383] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:18:57.383] INFO: ----------------------------------------------------------------------
[15:18:57.522] INFO: Expecting 768 events.
[15:18:58.659] INFO: 768 events read in total (422ms).
[15:18:58.660] INFO: Test took 1272ms.
[15:18:59.463] INFO: Expecting 41600 events.
[15:19:02.502] INFO: 41600 events read in total (2512ms).
[15:19:02.503] INFO: Test took 3838ms.
[15:19:02.533] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:03.268] INFO: Expecting 41600 events.
[15:19:06.451] INFO: 41600 events read in total (2656ms).
[15:19:06.452] INFO: Test took 3903ms.
[15:19:06.483] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:07.214] INFO: Expecting 41600 events.
[15:19:10.485] INFO: 41600 events read in total (2744ms).
[15:19:10.486] INFO: Test took 3986ms.
[15:19:10.518] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:11.249] INFO: Expecting 41600 events.
[15:19:14.493] INFO: 41600 events read in total (2717ms).
[15:19:14.494] INFO: Test took 3960ms.
[15:19:14.525] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:15.253] INFO: Expecting 41600 events.
[15:19:18.487] INFO: 41600 events read in total (2707ms).
[15:19:18.488] INFO: Test took 3947ms.
[15:19:18.519] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:19.245] INFO: Expecting 41600 events.
[15:19:22.462] INFO: 41600 events read in total (2690ms).
[15:19:22.463] INFO: Test took 3927ms.
[15:19:22.494] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:23.226] INFO: Expecting 41600 events.
[15:19:26.433] INFO: 41600 events read in total (2680ms).
[15:19:26.434] INFO: Test took 3924ms.
[15:19:26.465] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:27.196] INFO: Expecting 41600 events.
[15:19:30.392] INFO: 41600 events read in total (2669ms).
[15:19:30.393] INFO: Test took 3911ms.
[15:19:30.423] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:31.154] INFO: Expecting 41600 events.
[15:19:34.352] INFO: 41600 events read in total (2671ms).
[15:19:34.353] INFO: Test took 3912ms.
[15:19:34.383] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:35.115] INFO: Expecting 41600 events.
[15:19:38.315] INFO: 41600 events read in total (2673ms).
[15:19:38.316] INFO: Test took 3915ms.
[15:19:38.347] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:39.081] INFO: Expecting 41600 events.
[15:19:42.273] INFO: 41600 events read in total (2666ms).
[15:19:42.274] INFO: Test took 3910ms.
[15:19:42.305] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:43.036] INFO: Expecting 41600 events.
[15:19:46.196] INFO: 41600 events read in total (2633ms).
[15:19:46.197] INFO: Test took 3875ms.
[15:19:46.227] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:46.959] INFO: Expecting 41600 events.
[15:19:50.157] INFO: 41600 events read in total (2671ms).
[15:19:50.158] INFO: Test took 3914ms.
[15:19:50.188] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:50.920] INFO: Expecting 41600 events.
[15:19:54.138] INFO: 41600 events read in total (2691ms).
[15:19:54.139] INFO: Test took 3934ms.
[15:19:54.169] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:54.898] INFO: Expecting 41600 events.
[15:19:58.087] INFO: 41600 events read in total (2663ms).
[15:19:58.088] INFO: Test took 3902ms.
[15:19:58.119] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:58.848] INFO: Expecting 41600 events.
[15:20:02.014] INFO: 41600 events read in total (2639ms).
[15:20:02.015] INFO: Test took 3880ms.
[15:20:02.045] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:02.777] INFO: Expecting 41600 events.
[15:20:05.973] INFO: 41600 events read in total (2669ms).
[15:20:05.974] INFO: Test took 3912ms.
[15:20:05.004] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:06.736] INFO: Expecting 41600 events.
[15:20:09.932] INFO: 41600 events read in total (2669ms).
[15:20:09.933] INFO: Test took 3912ms.
[15:20:09.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:10.693] INFO: Expecting 41600 events.
[15:20:13.890] INFO: 41600 events read in total (2670ms).
[15:20:13.890] INFO: Test took 3910ms.
[15:20:13.921] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:14.648] INFO: Expecting 41600 events.
[15:20:17.731] INFO: 41600 events read in total (2556ms).
[15:20:17.731] INFO: Test took 3794ms.
[15:20:17.761] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:18.129] INFO: enter test to run
[15:21:29.236] INFO: test: HighRate no parameter change
[15:21:29.236] INFO: running: highrate
[15:21:29.237] INFO: ----------------------------------------------------------------------
[15:21:29.237] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:21:29.237] INFO: ----------------------------------------------------------------------
[15:21:29.855] INFO: Expecting 208000 events.
[15:21:41.468] INFO: 208000 events read in total (11086ms).
[15:21:41.471] INFO: Test took 12226ms.
[15:21:41.601] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:41.848] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[15:21:41.848] INFO: number of red-efficiency pixels: 62 48 57 106 139 98 92 97 88 98 95 83 98 46 17 20
[15:21:41.848] INFO: number of X-ray hits detected: 57524 38177 55397 88919 99020 93705 96424 73702 73135 86321 79905 72263 74212 44199 19977 22853
[15:21:41.848] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:21:41.848] INFO: number of Vcal hits detected: 207935 207949 207941 207894 207812 207899 207904 207902 207911 207898 207902 207916 207900 207954 207983 207980
[15:21:41.848] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[15:21:41.848] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[15:21:41.848] INFO: X-ray hit rate [MHz/cm2]: 16.9 11.2 16.2 26.1 29.0 27.5 28.3 21.6 21.4 25.3 23.4 21.2 21.8 13.0 5.9 6.7
[15:21:41.848] INFO: PixTestHighRate::doXPixelAlive() done
[15:21:41.893] INFO: PixTest:: pg_setup set to default.
[15:21:41.908] INFO: enter test to run
[15:22:03.628] INFO: test: HighRate no parameter change
[15:22:03.628] INFO: running: highrate
[15:22:03.629] INFO: ----------------------------------------------------------------------
[15:22:03.629] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:22:03.629] INFO: ----------------------------------------------------------------------
[15:22:04.243] INFO: Expecting 208000 events.
[15:22:17.291] INFO: 208000 events read in total (12521ms).
[15:22:17.296] INFO: Test took 13659ms.
[15:22:17.541] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:22:17.827] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[15:22:17.827] INFO: number of red-efficiency pixels: 139 97 177 293 387 277 322 262 224 289 259 197 215 144 46 46
[15:22:17.827] INFO: number of X-ray hits detected: 115167 76590 110169 178983 199027 187490 193635 148500 146541 175557 161601 145136 148961 89813 40208 46314
[15:22:17.827] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:22:17.827] INFO: number of Vcal hits detected: 207852 207900 207813 207701 207545 207707 207655 207722 207764 207693 207729 207793 207780 207851 207952 207953
[15:22:17.827] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[15:22:17.827] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[15:22:17.827] INFO: X-ray hit rate [MHz/cm2]: 33.8 22.4 32.3 52.5 58.3 55.0 56.8 43.5 43.0 51.5 47.4 42.5 43.7 26.3 11.8 13.6
[15:22:17.827] INFO: PixTestHighRate::doXPixelAlive() done
[15:22:17.873] INFO: PixTest:: pg_setup set to default.
[15:22:17.886] INFO: enter test to run
[15:22:47.291] INFO: test: HighRate no parameter change
[15:22:47.292] INFO: running: highrate
[15:22:47.293] INFO: ----------------------------------------------------------------------
[15:22:47.293] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:22:47.293] INFO: ----------------------------------------------------------------------
[15:22:47.908] INFO: Expecting 208000 events.
[15:23:02.586] INFO: 208000 events read in total (14151ms).
[15:23:02.593] INFO: Test took 15292ms.
[15:23:02.969] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:03.295] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[15:23:03.295] INFO: number of red-efficiency pixels: 271 224 400 738 905 624 734 538 501 620 525 469 571 288 81 83
[15:23:03.295] INFO: number of X-ray hits detected: 176378 116924 170337 274959 306128 287035 298323 229541 225224 269019 247039 222895 228623 138557 62226 71059
[15:23:03.295] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:23:03.295] INFO: number of Vcal hits detected: 207710 207754 207541 207165 206851 207309 207138 207359 207461 207302 207417 207484 207365 207688 207916 207915
[15:23:03.295] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.6 99.5 99.7 99.6 99.7 99.8 99.7 99.7 99.8 99.7 99.9 100.0 100.0
[15:23:03.295] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.6 99.4 99.7 99.6 99.7 99.7 99.7 99.7 99.8 99.7 99.9 100.0 100.0
[15:23:03.295] INFO: X-ray hit rate [MHz/cm2]: 51.7 34.3 49.9 80.6 89.7 84.1 87.4 67.3 66.0 78.9 72.4 65.3 67.0 40.6 18.2 20.8
[15:23:03.295] INFO: PixTestHighRate::doXPixelAlive() done
[15:23:03.342] INFO: PixTest:: pg_setup set to default.
[15:23:03.355] INFO: enter test to run
[15:24:21.059] INFO: test: exit no parameter change
[15:24:21.417] QUIET: Connection to board 32 closed.
[15:24:21.418] INFO: pXar: this is the end, my friend