[11:25:10.872] INFO: *** Welcome to pxar ***
[11:25:10.872] INFO: *** Today: 2016/05/27
[11:25:10.964] INFO: *** Version: v1.9.0-796-gef167-dirty
[11:25:10.965] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//dacParameters35_C15.dat
[11:25:10.999] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:25:10.999] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//defaultMaskFile.dat
[11:25:11.006] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C15.dat
[11:25:11.087] INFO: clk: 4
[11:25:11.087] INFO: ctr: 4
[11:25:11.087] INFO: sda: 19
[11:25:11.087] INFO: tin: 9
[11:25:11.087] INFO: level: 15
[11:25:11.087] INFO: triggerdelay: 0
[11:25:11.087] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[11:25:11.087] INFO: Log level: INFO
[11:25:11.102] QUIET: Connection to board DTB_WREKRL opened.
[11:25:11.106] 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:
------------------------------------------------------
[11:25:11.108] INFO: RPC call hashes of host and DTB match: 398089610
[11:25:12.642] INFO: DUT info:
[11:25:12.642] INFO: The DUT currently contains the following objects:
[11:25:12.642] INFO: 2 TBM Cores tbm08c (2 ON)
[11:25:12.642] INFO: TBM Core alpha (0): 7 registers set
[11:25:12.642] INFO: TBM Core beta (1): 7 registers set
[11:25:12.642] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:25:12.642] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:12.642] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:12.643] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:12.643] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:12.643] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:12.643] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:12.643] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:12.643] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:12.643] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:12.643] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:12.643] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:12.643] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:12.643] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:12.643] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:12.643] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:12.643] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:25:13.047] INFO: enter 'restricted' command line mode
[11:25:13.047] INFO: enter test to run
[11:25:24.844] INFO: test: PixelAlive no parameter change
[11:25:24.844] INFO: running: pixelalive
[11:25:24.853] INFO: ----------------------------------------------------------------------
[11:25:24.854] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:25:24.854] INFO: ----------------------------------------------------------------------
[11:25:25.173] INFO: Expecting 41600 events.
[11:25:29.323] INFO: 41600 events read in total (3431ms).
[11:25:29.491] INFO: Test took 4635ms.
[11:25:29.501] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:29.765] INFO: PixTestAlive::aliveTest() done
[11:25:29.765] INFO: number of dead pixels (per ROC): 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[11:25:29.797] INFO: enter test to run
[11:26:03.532] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[11:26:03.532] INFO: running: highrate
[11:26:03.563] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[11:26:03.861] INFO: ----------------------------------------------------------------------
[11:26:03.861] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[11:26:03.861] INFO: ----------------------------------------------------------------------
[11:26:03.861] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[11:26:03.861] INFO: edge/corner pixel THR is adjusted
[11:26:03.861] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:26:04.841] INFO: Collecting data for 5 seconds...
[11:26:09.858] INFO: Done with hot pixel readout
[11:26:20.785] INFO: PixTest:: pg_setup set to default.
[11:26:20.785] INFO: 0 hot pixels found in step 0
[11:26:20.798] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:26:20.802] INFO: PixTest::trimHotPixels() done
[11:26:20.844] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C0.dat
[11:26:20.850] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C1.dat
[11:26:20.855] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C2.dat
[11:26:20.860] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C3.dat
[11:26:20.865] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C4.dat
[11:26:20.871] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C5.dat
[11:26:20.876] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C6.dat
[11:26:20.882] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C7.dat
[11:26:20.887] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C8.dat
[11:26:20.892] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C9.dat
[11:26:20.897] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C10.dat
[11:26:20.902] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C11.dat
[11:26:20.908] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C12.dat
[11:26:20.913] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C13.dat
[11:26:20.918] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C14.dat
[11:26:20.923] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C15.dat
[11:26:20.928] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//defaultMaskFile.dat
[11:26:20.939] INFO: enter test to run
[11:27:36.684] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[11:27:36.684] INFO: running: highrate
[11:27:36.688] INFO: ----------------------------------------------------------------------
[11:27:36.688] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:27:36.688] INFO: ----------------------------------------------------------------------
[11:27:36.688] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:27:36.688] INFO: edge/corner pixel THR is adjusted
[11:27:36.688] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:27:37.646] INFO: Collecting data for 1 seconds...
[11:27:38.650] INFO: Done with hot pixel readout
[11:27:42.585] INFO: PixTest:: pg_setup set to default.
[11:27:42.586] INFO: 0 hot pixels found in step 0
[11:27:42.591] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:27:42.686] INFO: PixTest::trimHotPixels() done
[11:27:42.687] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C0.dat
[11:27:42.698] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C1.dat
[11:27:42.703] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C2.dat
[11:27:42.709] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C3.dat
[11:27:42.714] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C4.dat
[11:27:42.720] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C5.dat
[11:27:42.725] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C6.dat
[11:27:42.730] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C7.dat
[11:27:42.735] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C8.dat
[11:27:42.741] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C9.dat
[11:27:42.746] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C10.dat
[11:27:42.751] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C11.dat
[11:27:42.757] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C12.dat
[11:27:42.762] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C13.dat
[11:27:42.767] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C14.dat
[11:27:42.773] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//trimParameters35_C15.dat
[11:27:42.778] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-1-15_FPIXTest-17C-FNAL-160520-1330_2016-05-20_13h30m_1463769035/000_FPIXTest_p17//defaultMaskFile.dat
[11:27:42.794] INFO: enter test to run
[11:28:03.539] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:28:03.539] INFO: running: xray
[11:28:03.552] INFO: ----------------------------------------------------------------------
[11:28:03.553] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:28:03.553] INFO: ----------------------------------------------------------------------
[11:28:04.514] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:28:15.963] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:28:44.537] INFO: Resuming triggers.
[11:28:55.987] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[11:29:24.771] INFO: Resuming triggers.
[11:29:36.229] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[11:30:04.910] INFO: Resuming triggers.
[11:30:16.364] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[11:30:45.088] INFO: Resuming triggers.
[11:30:56.542] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[11:31:24.691] INFO: Resuming triggers.
[11:31:36.149] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[11:32:04.852] INFO: Resuming triggers.
[11:32:16.309] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[11:32:45.013] INFO: Resuming triggers.
[11:32:56.475] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[11:33:24.766] INFO: Resuming triggers.
[11:33:33.454] INFO: data taking finished, elapsed time: 100 seconds.
[11:33:54.702] INFO: PixTest:: pg_setup set to default.
[11:33:54.705] INFO: PixTestXray::doPhRun() done
[11:33:54.883] INFO: enter test to run
[11:35:07.608] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:35:07.608] INFO: running: xray
[11:35:07.609] INFO: ----------------------------------------------------------------------
[11:35:07.609] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:35:07.609] INFO: ----------------------------------------------------------------------
[11:35:08.573] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:35:15.374] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[11:35:45.589] INFO: Resuming triggers.
[11:35:52.388] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[11:36:22.274] INFO: Resuming triggers.
[11:36:29.080] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[11:36:58.693] INFO: Resuming triggers.
[11:37:05.492] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[11:37:35.121] INFO: Resuming triggers.
[11:37:41.927] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[11:38:11.661] INFO: Resuming triggers.
[11:38:18.461] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[11:38:48.095] INFO: Resuming triggers.
[11:38:54.893] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[11:39:24.510] INFO: Resuming triggers.
[11:39:31.306] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[11:40:00.904] INFO: Resuming triggers.
[11:40:07.704] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[11:40:37.411] INFO: Resuming triggers.
[11:40:44.207] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[11:41:13.950] INFO: Resuming triggers.
[11:41:20.747] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[11:41:50.330] INFO: Resuming triggers.
[11:41:57.131] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[11:42:26.454] INFO: Resuming triggers.
[11:42:33.260] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[11:43:03.014] INFO: Resuming triggers.
[11:43:09.816] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[11:43:39.452] INFO: Resuming triggers.
[11:43:44.566] INFO: data taking finished, elapsed time: 100 seconds.
[11:44:06.888] INFO: PixTest:: pg_setup set to default.
[11:44:06.891] INFO: PixTestXray::doPhRun() done
[11:44:07.040] INFO: enter test to run
[11:46:29.995] INFO: test: HighRate no parameter change
[11:46:29.995] INFO: running: highrate
[11:46:29.996] INFO: ----------------------------------------------------------------------
[11:46:29.996] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:46:29.996] INFO: ----------------------------------------------------------------------
[11:46:30.136] INFO: Expecting 768 events.
[11:46:31.282] INFO: 768 events read in total (423ms).
[11:46:31.282] INFO: Test took 1280ms.
[11:46:32.085] INFO: Expecting 41600 events.
[11:46:35.154] INFO: 41600 events read in total (2542ms).
[11:46:35.156] INFO: Test took 3847ms.
[11:46:35.189] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:35.920] INFO: Expecting 41600 events.
[11:46:38.972] INFO: 41600 events read in total (2526ms).
[11:46:38.973] INFO: Test took 3767ms.
[11:46:39.008] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:39.732] INFO: Expecting 41600 events.
[11:46:42.985] INFO: 41600 events read in total (2726ms).
[11:46:42.986] INFO: Test took 3959ms.
[11:46:43.020] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:43.744] INFO: Expecting 41600 events.
[11:46:46.958] INFO: 41600 events read in total (2687ms).
[11:46:46.959] INFO: Test took 3921ms.
[11:46:46.995] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:47.718] INFO: Expecting 41600 events.
[11:46:50.907] INFO: 41600 events read in total (2662ms).
[11:46:50.908] INFO: Test took 3896ms.
[11:46:50.943] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:51.667] INFO: Expecting 41600 events.
[11:46:54.888] INFO: 41600 events read in total (2694ms).
[11:46:54.889] INFO: Test took 3929ms.
[11:46:54.923] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:55.651] INFO: Expecting 41600 events.
[11:46:58.893] INFO: 41600 events read in total (2715ms).
[11:46:58.894] INFO: Test took 3953ms.
[11:46:58.929] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:59.648] INFO: Expecting 41600 events.
[11:47:02.802] INFO: 41600 events read in total (2627ms).
[11:47:02.803] INFO: Test took 3856ms.
[11:47:02.838] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:03.551] INFO: Expecting 41600 events.
[11:47:06.768] INFO: 41600 events read in total (2690ms).
[11:47:06.768] INFO: Test took 3912ms.
[11:47:06.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:07.525] INFO: Expecting 41600 events.
[11:47:10.760] INFO: 41600 events read in total (2708ms).
[11:47:10.761] INFO: Test took 3940ms.
[11:47:10.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:11.523] INFO: Expecting 41600 events.
[11:47:14.623] INFO: 41600 events read in total (2573ms).
[11:47:14.624] INFO: Test took 3812ms.
[11:47:14.658] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:15.369] INFO: Expecting 41600 events.
[11:47:18.579] INFO: 41600 events read in total (2683ms).
[11:47:18.580] INFO: Test took 3903ms.
[11:47:18.615] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:19.339] INFO: Expecting 41600 events.
[11:47:22.608] INFO: 41600 events read in total (2742ms).
[11:47:22.609] INFO: Test took 3977ms.
[11:47:22.644] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:23.364] INFO: Expecting 41600 events.
[11:47:26.577] INFO: 41600 events read in total (2686ms).
[11:47:26.578] INFO: Test took 3917ms.
[11:47:26.613] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:27.331] INFO: Expecting 41600 events.
[11:47:30.473] INFO: 41600 events read in total (2616ms).
[11:47:30.474] INFO: Test took 3844ms.
[11:47:30.508] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:31.234] INFO: Expecting 41600 events.
[11:47:34.391] INFO: 41600 events read in total (2630ms).
[11:47:34.392] INFO: Test took 3867ms.
[11:47:34.427] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:35.146] INFO: Expecting 41600 events.
[11:47:38.461] INFO: 41600 events read in total (2788ms).
[11:47:38.462] INFO: Test took 4017ms.
[11:47:38.496] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:39.218] INFO: Expecting 41600 events.
[11:47:42.444] INFO: 41600 events read in total (2699ms).
[11:47:42.445] INFO: Test took 3930ms.
[11:47:42.480] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:43.201] INFO: Expecting 41600 events.
[11:47:46.373] INFO: 41600 events read in total (2645ms).
[11:47:46.374] INFO: Test took 3876ms.
[11:47:46.409] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:47.122] INFO: Expecting 41600 events.
[11:47:50.188] INFO: 41600 events read in total (2539ms).
[11:47:50.189] INFO: Test took 3761ms.
[11:47:50.223] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:50.578] INFO: enter test to run
[11:49:07.258] INFO: test: HighRate no parameter change
[11:49:07.258] INFO: running: highrate
[11:49:07.259] INFO: ----------------------------------------------------------------------
[11:49:07.259] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:49:07.259] INFO: ----------------------------------------------------------------------
[11:49:07.871] INFO: Expecting 208000 events.
[11:49:19.385] INFO: 208000 events read in total (10987ms).
[11:49:19.388] INFO: Test took 12120ms.
[11:49:19.532] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:49:19.783] INFO: number of dead pixels (per ROC): 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[11:49:19.783] INFO: number of red-efficiency pixels: 60 47 70 136 142 180 151 95 94 132 111 258 97 56 22 26
[11:49:19.783] INFO: number of X-ray hits detected: 64579 45157 66099 102997 111097 114748 111762 86163 81775 93699 94719 82321 85311 49476 21469 23443
[11:49:19.783] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:49:19.784] INFO: number of Vcal hits detected: 207936 207953 207927 207862 207856 207764 207840 207905 207905 207867 207888 201364 207902 207942 207978 207973
[11:49:19.784] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 96.7 100.0 100.0 100.0 100.0
[11:49:19.784] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 96.8 100.0 100.0 100.0 100.0
[11:49:19.784] INFO: X-ray hit rate [MHz/cm2]: 18.9 13.2 19.4 30.2 32.6 33.6 32.8 25.3 24.0 27.5 27.8 24.1 25.0 14.5 6.3 6.9
[11:49:19.784] INFO: PixTestHighRate::doXPixelAlive() done
[11:49:19.829] INFO: PixTest:: pg_setup set to default.
[11:49:19.837] INFO: enter test to run
[11:49:34.417] INFO: test: HighRate no parameter change
[11:49:34.417] INFO: running: highrate
[11:49:34.418] INFO: ----------------------------------------------------------------------
[11:49:34.418] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:49:34.418] INFO: ----------------------------------------------------------------------
[11:49:35.029] INFO: Expecting 208000 events.
[11:49:48.408] INFO: 208000 events read in total (12852ms).
[11:49:48.413] INFO: Test took 13988ms.
[11:49:48.699] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:49:48.995] INFO: number of dead pixels (per ROC): 0 0 0 0 0 1 0 0 0 0 0 4 0 0 0 0
[11:49:48.996] INFO: number of red-efficiency pixels: 215 143 219 405 464 555 490 255 208 360 349 461 293 191 59 54
[11:49:48.996] INFO: number of X-ray hits detected: 130771 90950 134013 207812 225093 231501 226262 174722 165636 188904 191968 167133 173728 99732 43445 47648
[11:49:48.996] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:49:48.996] INFO: number of Vcal hits detected: 207772 207851 207758 207560 207487 207328 207455 207727 207789 207623 207632 200190 207693 207786 207938 207946
[11:49:48.996] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.8 99.9 99.9 99.8 99.8 96.2 99.9 99.9 100.0 100.0
[11:49:48.996] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.7 99.9 99.9 99.8 99.8 96.2 99.9 99.9 100.0 100.0
[11:49:48.996] INFO: X-ray hit rate [MHz/cm2]: 38.3 26.7 39.3 60.9 66.0 67.9 66.3 51.2 48.5 55.4 56.3 49.0 50.9 29.2 12.7 14.0
[11:49:48.996] INFO: PixTestHighRate::doXPixelAlive() done
[11:49:49.041] INFO: PixTest:: pg_setup set to default.
[11:49:49.052] INFO: enter test to run
[11:50:19.073] INFO: test: HighRate no parameter change
[11:50:19.073] INFO: running: highrate
[11:50:19.075] INFO: ----------------------------------------------------------------------
[11:50:19.075] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:50:19.075] INFO: ----------------------------------------------------------------------
[11:50:19.690] INFO: Expecting 208000 events.
[11:50:34.915] INFO: 208000 events read in total (14699ms).
[11:50:34.927] INFO: Test took 15844ms.
[11:50:35.371] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:50:35.715] INFO: number of dead pixels (per ROC): 0 0 0 0 0 1 0 0 0 0 0 55 0 0 0 0
[11:50:35.715] INFO: number of red-efficiency pixels: 459 279 545 961 1072 1303 1192 595 451 824 842 854 691 336 85 77
[11:50:35.715] INFO: number of X-ray hits detected: 196672 137412 202534 315633 340104 348903 342540 265204 251062 287520 290628 253725 261989 152226 65888 71889
[11:50:35.715] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:50:35.715] INFO: number of Vcal hits detected: 207467 207693 207325 206825 206659 206264 206507 207322 207522 207046 207008 199358 207227 207614 207912 207922
[11:50:35.715] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.4 99.3 99.4 99.7 99.8 99.6 99.6 97.1 99.7 99.8 100.0 100.0
[11:50:35.715] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.7 99.4 99.4 99.2 99.3 99.7 99.8 99.5 99.5 95.8 99.6 99.8 100.0 100.0
[11:50:35.715] INFO: X-ray hit rate [MHz/cm2]: 57.6 40.3 59.4 92.5 99.7 102.3 100.4 77.7 73.6 84.3 85.2 74.4 76.8 44.6 19.3 21.1
[11:50:35.715] INFO: PixTestHighRate::doXPixelAlive() done
[11:50:35.765] INFO: PixTest:: pg_setup set to default.
[11:50:35.779] INFO: enter test to run
[11:51:53.249] INFO: test: exit no parameter change
[11:51:53.572] QUIET: Connection to board 33 closed.
[11:51:53.587] INFO: pXar: this is the end, my friend