[09:30:31.865] INFO: *** Welcome to pxar ***
[09:30:31.865] INFO: *** Today: 2016/04/28
[09:30:32.298] INFO: *** Version: v1.9.0-796-gef167-dirty
[09:30:32.298] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//dacParameters35_C15.dat
[09:30:32.372] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//tbmParameters_C0b.dat
[09:30:32.372] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//defaultMaskFile.dat
[09:30:32.378] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C15.dat
[09:30:32.530] INFO: clk: 4
[09:30:32.530] INFO: ctr: 4
[09:30:32.530] INFO: sda: 19
[09:30:32.530] INFO: tin: 9
[09:30:32.530] INFO: level: 15
[09:30:32.530] INFO: triggerdelay: 0
[09:30:32.530] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[09:30:32.530] INFO: Log level: INFO
[09:30:32.548] QUIET: Connection to board DTB_WREKRL opened.
[09:30:32.552] 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:
------------------------------------------------------
[09:30:32.555] INFO: RPC call hashes of host and DTB match: 398089610
[09:30:34.101] INFO: DUT info:
[09:30:34.101] INFO: The DUT currently contains the following objects:
[09:30:34.101] INFO: 2 TBM Cores tbm08c (2 ON)
[09:30:34.114] INFO: TBM Core alpha (0): 7 registers set
[09:30:34.114] INFO: TBM Core beta (1): 7 registers set
[09:30:34.114] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:30:34.114] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.114] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.114] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.114] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.115] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.115] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.115] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.115] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.115] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.115] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.115] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.115] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.115] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.115] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.115] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.115] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:30:34.585] INFO: enter 'restricted' command line mode
[09:30:34.585] INFO: enter test to run
[09:30:51.979] INFO: test: PixelAlive no parameter change
[09:30:51.979] INFO: running: pixelalive
[09:30:52.078] INFO: ----------------------------------------------------------------------
[09:30:52.078] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:30:52.078] INFO: ----------------------------------------------------------------------
[09:30:52.393] INFO: Expecting 41600 events.
[09:30:56.743] INFO: 41600 events read in total (3632ms).
[09:30:56.913] INFO: Test took 4832ms.
[09:30:56.924] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:57.510] INFO: PixTestAlive::aliveTest() done
[09:30:57.510] INFO: number of dead pixels (per ROC): 0 0 7 2 0 0 0 0 0 0 0 0 0 0 0 0
[09:30:57.673] INFO: enter test to run
[09:31:33.930] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[09:31:33.930] INFO: running: highrate
[09:31:33.931] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[09:31:34.206] INFO: ----------------------------------------------------------------------
[09:31:34.206] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[09:31:34.206] INFO: ----------------------------------------------------------------------
[09:31:34.206] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[09:31:34.206] INFO: edge/corner pixel THR is adjusted
[09:31:34.206] INFO: PixTestHighRate::trimHotPixels: step 0...
[09:31:35.166] INFO: Collecting data for 5 seconds...
[09:31:40.182] INFO: Done with hot pixel readout
[09:31:52.076] INFO: PixTest:: pg_setup set to default.









[09:31:52.077] INFO: 9 hot pixels found in step 0
[09:31:52.089] INFO: 9 hot pixels could not be trimmed and have been masked.
[09:31:52.093] INFO: PixTest::trimHotPixels() done
[09:31:52.119] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C0.dat
[09:31:52.124] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C1.dat
[09:31:52.130] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C2.dat
[09:31:52.135] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C3.dat
[09:31:52.140] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C4.dat
[09:31:52.145] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C5.dat
[09:31:52.151] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C6.dat
[09:31:52.156] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C7.dat
[09:31:52.161] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C8.dat
[09:31:52.166] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C9.dat
[09:31:52.172] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C10.dat
[09:31:52.177] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C11.dat
[09:31:52.182] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C12.dat
[09:31:52.187] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C13.dat
[09:31:52.193] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C14.dat
[09:31:52.198] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C15.dat
[09:31:52.203] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//defaultMaskFile.dat
[09:31:52.213] INFO: enter test to run
[09:32:15.450] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[09:32:15.450] INFO: running: highrate
[09:32:15.454] INFO: ----------------------------------------------------------------------
[09:32:15.454] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[09:32:15.454] INFO: ----------------------------------------------------------------------
[09:32:15.454] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[09:32:15.454] INFO: edge/corner pixel THR is adjusted
[09:32:15.455] INFO: PixTestHighRate::trimHotPixels: step 0...
[09:32:16.412] INFO: Collecting data for 1 seconds...
[09:32:17.416] INFO: Done with hot pixel readout
[09:32:21.621] INFO: PixTest:: pg_setup set to default.

[09:32:21.622] INFO: 1 hot pixels found in step 0
[09:32:21.627] INFO: 1 hot pixels could not be trimmed and have been masked.
[09:32:21.711] INFO: PixTest::trimHotPixels() done
[09:32:21.712] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C0.dat
[09:32:21.724] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C1.dat
[09:32:21.730] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C2.dat
[09:32:21.735] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C3.dat
[09:32:21.741] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C4.dat
[09:32:21.746] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C5.dat
[09:32:21.751] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C6.dat
[09:32:21.756] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C7.dat
[09:32:21.762] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C8.dat
[09:32:21.767] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C9.dat
[09:32:21.772] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C10.dat
[09:32:21.778] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C11.dat
[09:32:21.783] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C12.dat
[09:32:21.788] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C13.dat
[09:32:21.793] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C14.dat
[09:32:21.799] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//trimParameters35_C15.dat
[09:32:21.804] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-K-3-41_FPIXTest-17C-FNAL-160422-1201_2016-04-22_12h01m_1461344476/000_FPIXTest_p17//defaultMaskFile.dat
[09:32:21.814] INFO: enter test to run
[09:32:46.034] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[09:32:46.034] INFO: running: xray
[09:32:46.035] INFO: ----------------------------------------------------------------------
[09:32:46.035] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:32:46.035] INFO: ----------------------------------------------------------------------
[09:32:47.016] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:32:58.297] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[09:33:27.687] INFO: Resuming triggers.
[09:33:38.972] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[09:34:08.504] INFO: Resuming triggers.
[09:34:19.789] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[09:34:49.168] INFO: Resuming triggers.
[09:35:00.453] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[09:35:29.862] INFO: Resuming triggers.
[09:35:41.149] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[09:36:10.598] INFO: Resuming triggers.
[09:36:21.882] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[09:36:51.339] INFO: Resuming triggers.
[09:37:02.626] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[09:37:32.054] INFO: Resuming triggers.
[09:37:43.339] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[09:38:12.715] INFO: Resuming triggers.
[09:38:22.760] INFO: data taking finished, elapsed time: 100 seconds.
[09:38:48.975] INFO: PixTest:: pg_setup set to default.
[09:38:48.978] INFO: PixTestXray::doPhRun() done
[09:38:49.178] INFO: enter test to run
[09:39:33.951] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[09:39:33.951] INFO: running: xray
[09:39:33.952] INFO: ----------------------------------------------------------------------
[09:39:33.952] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:39:33.952] INFO: ----------------------------------------------------------------------
[09:39:34.915] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:39:41.165] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[09:40:09.888] INFO: Resuming triggers.
[09:40:16.136] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[09:40:46.097] INFO: Resuming triggers.
[09:40:52.346] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[09:41:22.460] INFO: Resuming triggers.
[09:41:28.707] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[09:41:58.714] INFO: Resuming triggers.
[09:42:04.961] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[09:42:34.003] INFO: Resuming triggers.
[09:42:41.249] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[09:43:11.237] INFO: Resuming triggers.
[09:43:17.480] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[09:43:47.575] INFO: Resuming triggers.
[09:43:53.819] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[09:44:23.896] INFO: Resuming triggers.
[09:44:30.138] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[09:45:00.096] INFO: Resuming triggers.
[09:45:06.338] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[09:45:36.397] INFO: Resuming triggers.
[09:45:42.641] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[09:46:12.742] INFO: Resuming triggers.
[09:46:18.982] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[09:46:48.978] INFO: Resuming triggers.
[09:46:55.222] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[09:47:25.255] INFO: Resuming triggers.
[09:47:31.496] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[09:48:01.652] INFO: Resuming triggers.
[09:48:07.896] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[09:48:38.060] INFO: Resuming triggers.
[09:48:44.304] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[09:49:14.357] INFO: Resuming triggers.
[09:49:14.759] INFO: data taking finished, elapsed time: 100 seconds.
[09:49:17.076] INFO: PixTest:: pg_setup set to default.
[09:49:17.080] INFO: PixTestXray::doPhRun() done
[09:49:17.232] INFO: enter test to run
[09:49:37.466] INFO: test: HighRate no parameter change
[09:49:37.466] INFO: running: highrate
[09:49:37.468] INFO: ----------------------------------------------------------------------
[09:49:37.468] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[09:49:37.468] INFO: ----------------------------------------------------------------------
[09:49:37.607] INFO: Expecting 768 events.
[09:49:38.741] INFO: 768 events read in total (418ms).
[09:49:38.742] INFO: Test took 1269ms.
[09:49:39.545] INFO: Expecting 41600 events.
[09:49:42.629] INFO: 41600 events read in total (2558ms).
[09:49:42.630] INFO: Test took 3880ms.
[09:49:42.665] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:43.392] INFO: Expecting 41600 events.
[09:49:46.561] INFO: 41600 events read in total (2642ms).
[09:49:46.562] INFO: Test took 3877ms.
[09:49:46.597] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:47.318] INFO: Expecting 41600 events.
[09:49:50.560] INFO: 41600 events read in total (2715ms).
[09:49:50.561] INFO: Test took 3944ms.
[09:49:50.597] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:51.318] INFO: Expecting 41600 events.
[09:49:54.572] INFO: 41600 events read in total (2727ms).
[09:49:54.573] INFO: Test took 3959ms.
[09:49:54.609] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:55.332] INFO: Expecting 41600 events.
[09:49:58.576] INFO: 41600 events read in total (2717ms).
[09:49:58.577] INFO: Test took 3949ms.
[09:49:58.613] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:59.333] INFO: Expecting 41600 events.
[09:50:02.537] INFO: 41600 events read in total (2677ms).
[09:50:02.538] INFO: Test took 3905ms.
[09:50:02.574] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:03.292] INFO: Expecting 41600 events.
[09:50:06.123] INFO: 41600 events read in total (2304ms).
[09:50:06.124] INFO: Test took 3531ms.
[09:50:06.161] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:06.884] INFO: Expecting 41600 events.
[09:50:09.712] INFO: 41600 events read in total (2302ms).
[09:50:09.713] INFO: Test took 3532ms.
[09:50:09.750] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:10.473] INFO: Expecting 41600 events.
[09:50:13.347] INFO: 41600 events read in total (2347ms).
[09:50:13.348] INFO: Test took 3581ms.
[09:50:13.385] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:14.100] INFO: Expecting 41600 events.
[09:50:17.153] INFO: 41600 events read in total (2526ms).
[09:50:17.154] INFO: Test took 3750ms.
[09:50:17.191] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:17.913] INFO: Expecting 41600 events.
[09:50:20.732] INFO: 41600 events read in total (2292ms).
[09:50:20.734] INFO: Test took 3524ms.
[09:50:20.770] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:21.491] INFO: Expecting 41600 events.
[09:50:24.752] INFO: 41600 events read in total (2734ms).
[09:50:24.753] INFO: Test took 3964ms.
[09:50:24.789] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:25.510] INFO: Expecting 41600 events.
[09:50:28.760] INFO: 41600 events read in total (2723ms).
[09:50:28.761] INFO: Test took 3952ms.
[09:50:28.797] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:29.521] INFO: Expecting 41600 events.
[09:50:32.740] INFO: 41600 events read in total (2692ms).
[09:50:32.741] INFO: Test took 3926ms.
[09:50:32.778] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:33.494] INFO: Expecting 41600 events.
[09:50:36.762] INFO: 41600 events read in total (2741ms).
[09:50:36.763] INFO: Test took 3966ms.
[09:50:36.798] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:37.519] INFO: Expecting 41600 events.
[09:50:40.779] INFO: 41600 events read in total (2733ms).
[09:50:40.780] INFO: Test took 3964ms.
[09:50:40.816] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:41.535] INFO: Expecting 41600 events.
[09:50:44.791] INFO: 41600 events read in total (2729ms).
[09:50:44.792] INFO: Test took 3956ms.
[09:50:44.827] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:45.550] INFO: Expecting 41600 events.
[09:50:48.796] INFO: 41600 events read in total (2719ms).
[09:50:48.797] INFO: Test took 3952ms.
[09:50:48.833] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:49.552] INFO: Expecting 41600 events.
[09:50:52.804] INFO: 41600 events read in total (2726ms).
[09:50:52.805] INFO: Test took 3952ms.
[09:50:52.841] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:53.560] INFO: Expecting 41600 events.
[09:50:56.714] INFO: 41600 events read in total (2627ms).
[09:50:56.716] INFO: Test took 3857ms.
[09:50:56.751] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:57.111] INFO: enter test to run
[09:51:04.170] INFO: test: HighRate no parameter change
[09:51:04.170] INFO: running: highrate
[09:51:04.171] INFO: ----------------------------------------------------------------------
[09:51:04.171] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:51:04.171] INFO: ----------------------------------------------------------------------
[09:51:04.786] INFO: Expecting 208000 events.
[09:51:16.720] INFO: 208000 events read in total (11407ms).
[09:51:16.724] INFO: Test took 12545ms.
[09:51:16.879] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:51:17.134] INFO: number of dead pixels (per ROC): 0 0 7 2 0 0 0 0 0 0 0 0 0 0 0 0
[09:51:17.134] INFO: number of red-efficiency pixels: 78 45 107 145 191 158 133 105 92 137 134 96 103 71 22 21
[09:51:17.134] INFO: number of X-ray hits detected: 71863 47575 73063 114442 132286 123575 126417 74193 72500 108381 102318 90591 95229 55592 24136 27682
[09:51:17.134] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:51:17.134] INFO: number of Vcal hits detected: 207920 207955 207546 207752 207798 207835 207864 207895 207904 207861 207859 207902 207897 207928 207978 207978
[09:51:17.134] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[09:51:17.134] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.8 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[09:51:17.134] INFO: X-ray hit rate [MHz/cm2]: 21.1 13.9 21.4 33.5 38.8 36.2 37.1 21.7 21.3 31.8 30.0 26.6 27.9 16.3 7.1 8.1
[09:51:17.134] INFO: PixTestHighRate::doXPixelAlive() done
[09:51:17.181] INFO: PixTest:: pg_setup set to default.
[09:51:17.198] INFO: enter test to run
[09:51:38.745] INFO: test: HighRate no parameter change
[09:51:38.745] INFO: running: highrate
[09:51:38.746] INFO: ----------------------------------------------------------------------
[09:51:38.747] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:51:38.747] INFO: ----------------------------------------------------------------------
[09:51:39.359] INFO: Expecting 208000 events.
[09:51:53.339] INFO: 208000 events read in total (13453ms).
[09:51:53.345] INFO: Test took 14591ms.
[09:51:53.666] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:51:53.974] INFO: number of dead pixels (per ROC): 0 0 7 2 0 0 0 0 0 0 0 0 0 0 0 0
[09:51:53.974] INFO: number of red-efficiency pixels: 259 114 316 420 775 719 498 318 261 543 468 283 436 248 64 82
[09:51:53.974] INFO: number of X-ray hits detected: 152606 101989 157354 247212 283103 263530 269835 160089 155187 231631 219700 193524 204107 119353 51569 60113
[09:51:53.974] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:51:53.974] INFO: number of Vcal hits detected: 207725 207882 207312 207447 207149 207217 207455 207656 207724 207410 207493 207697 207532 207735 207935 207918
[09:51:53.974] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.6 99.6 99.8 99.8 99.9 99.7 99.8 99.9 99.8 99.9 100.0 100.0
[09:51:53.974] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.7 99.6 99.6 99.7 99.8 99.9 99.7 99.8 99.9 99.8 99.9 100.0 100.0
[09:51:53.974] INFO: X-ray hit rate [MHz/cm2]: 44.7 29.9 46.1 72.5 83.0 77.2 79.1 46.9 45.5 67.9 64.4 56.7 59.8 35.0 15.1 17.6
[09:51:53.975] INFO: PixTestHighRate::doXPixelAlive() done
[09:51:54.019] INFO: PixTest:: pg_setup set to default.
[09:51:54.034] INFO: enter test to run
[09:52:19.481] INFO: test: HighRate no parameter change
[09:52:19.481] INFO: running: highrate
[09:52:19.482] INFO: ----------------------------------------------------------------------
[09:52:19.482] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:52:19.482] INFO: ----------------------------------------------------------------------
[09:52:20.097] INFO: Expecting 208000 events.
[09:52:36.442] INFO: 208000 events read in total (15818ms).
[09:52:36.451] INFO: Test took 16959ms.
[09:52:36.966] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:52:37.335] INFO: number of dead pixels (per ROC): 0 0 7 2 0 0 0 0 0 0 0 0 0 0 0 0
[09:52:37.335] INFO: number of red-efficiency pixels: 529 271 881 1074 1955 1703 1300 789 535 1394 1239 661 1066 579 120 122
[09:52:37.335] INFO: number of X-ray hits detected: 237439 157358 242900 379635 437794 407318 419519 248369 241542 359502 340240 299263 317551 185859 80534 92137
[09:52:37.335] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:52:37.335] INFO: number of Vcal hits detected: 207365 207697 206496 206620 205145 205671 206287 207002 207412 206197 206476 207258 206705 207293 207872 207872
[09:52:37.335] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.4 99.4 98.7 98.9 99.3 99.6 99.7 99.2 99.3 99.7 99.4 99.7 99.9 99.9
[09:52:37.335] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.3 99.3 98.6 98.9 99.2 99.5 99.7 99.1 99.3 99.6 99.4 99.7 99.9 99.9
[09:52:37.335] INFO: X-ray hit rate [MHz/cm2]: 69.6 46.1 71.2 111.3 128.3 119.4 123.0 72.8 70.8 105.4 99.7 87.7 93.1 54.5 23.6 27.0
[09:52:37.335] INFO: PixTestHighRate::doXPixelAlive() done
[09:52:37.382] INFO: PixTest:: pg_setup set to default.
[09:52:37.400] INFO: enter test to run
[09:52:45.201] INFO: test: exit no parameter change
[09:52:45.596] QUIET: Connection to board 33 closed.
[09:52:45.597] INFO: pXar: this is the end, my friend