[11:17:05.898] INFO: *** Welcome to pxar ***
[11:17:05.898] INFO: *** Today: 2016/06/02
[11:17:05.918] INFO: *** Version: v1.9.0-796-gef167-dirty
[11:17:05.918] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//dacParameters35_C15.dat
[11:17:05.953] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:17:05.953] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//defaultMaskFile.dat
[11:17:05.953] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C15.dat
[11:17:06.048] INFO: clk: 4
[11:17:06.048] INFO: ctr: 4
[11:17:06.048] INFO: sda: 19
[11:17:06.048] INFO: tin: 9
[11:17:06.048] INFO: level: 15
[11:17:06.048] INFO: triggerdelay: 0
[11:17:06.048] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[11:17:06.048] INFO: Log level: INFO
[11:17:06.067] QUIET: Connection to board DTB_WREKRL opened.
[11:17:06.070] 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:17:06.073] INFO: RPC call hashes of host and DTB match: 398089610
[11:17:07.605] INFO: DUT info:
[11:17:07.605] INFO: The DUT currently contains the following objects:
[11:17:07.606] INFO: 2 TBM Cores tbm08c (2 ON)
[11:17:07.606] INFO: TBM Core alpha (0): 7 registers set
[11:17:07.606] INFO: TBM Core beta (1): 7 registers set
[11:17:07.606] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:17:07.606] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:07.606] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:07.606] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:07.606] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:07.606] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:07.606] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:07.606] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:07.606] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:07.606] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:07.606] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:07.607] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:07.607] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:07.607] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:07.607] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:07.607] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:07.607] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:08.010] INFO: enter 'restricted' command line mode
[11:17:08.010] INFO: enter test to run
[11:17:16.253] INFO: test: PixelAlive no parameter change
[11:17:16.253] INFO: running: pixelalive
[11:17:16.262] INFO: ----------------------------------------------------------------------
[11:17:16.262] 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:17:16.262] INFO: ----------------------------------------------------------------------
[11:17:16.584] INFO: Expecting 41600 events.
[11:17:20.904] INFO: 41600 events read in total (3602ms).
[11:17:21.074] INFO: Test took 4809ms.
[11:17:21.082] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:21.344] INFO: PixTestAlive::aliveTest() done
[11:17:21.344] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:17:21.377] INFO: enter test to run
[11:17:55.365] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[11:17:55.365] INFO: running: highrate
[11:17:55.365] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[11:17:55.561] INFO: ----------------------------------------------------------------------
[11:17:55.561] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[11:17:55.561] INFO: ----------------------------------------------------------------------
[11:17:55.561] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[11:17:55.561] INFO: edge/corner pixel THR is adjusted
[11:17:55.561] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:17:56.521] INFO: Collecting data for 5 seconds...
[11:18:01.537] INFO: Done with hot pixel readout
[11:18:13.162] INFO: PixTest:: pg_setup set to default.
[11:18:13.163] INFO: 1 hot pixels found in step 0
[11:18:14.172] INFO: Collecting data for 5 seconds...
[11:18:19.189] INFO: Done with hot pixel readout
[11:18:30.677] INFO: PixTest:: pg_setup set to default.
[11:18:30.678] INFO: 2 hot pixels found in step 1
[11:18:31.670] INFO: Collecting data for 5 seconds...
[11:18:36.688] INFO: Done with hot pixel readout
[11:18:48.425] INFO: PixTest:: pg_setup set to default.
[11:18:48.426] INFO: 0 hot pixels found in step 2
[11:18:48.462] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:18:48.465] INFO: PixTest::trimHotPixels() done
[11:18:48.466] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C0.dat
[11:18:48.471] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C1.dat
[11:18:48.477] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C2.dat
[11:18:48.482] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C3.dat
[11:18:48.487] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C4.dat
[11:18:48.493] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C5.dat
[11:18:48.498] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C6.dat
[11:18:48.503] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C7.dat
[11:18:48.508] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C8.dat
[11:18:48.514] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C9.dat
[11:18:48.519] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C10.dat
[11:18:48.524] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C11.dat
[11:18:48.530] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C12.dat
[11:18:48.535] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C13.dat
[11:18:48.540] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C14.dat
[11:18:48.546] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C15.dat
[11:18:48.551] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//defaultMaskFile.dat
[11:18:48.561] INFO: enter test to run
[11:20:07.756] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[11:20:07.756] INFO: running: highrate
[11:20:07.760] INFO: ----------------------------------------------------------------------
[11:20:07.760] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:20:07.760] INFO: ----------------------------------------------------------------------
[11:20:07.760] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:20:07.760] INFO: edge/corner pixel THR is adjusted
[11:20:07.760] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:20:08.718] INFO: Collecting data for 1 seconds...
[11:20:09.722] INFO: Done with hot pixel readout
[11:20:13.691] INFO: PixTest:: pg_setup set to default.
[11:20:13.692] INFO: 0 hot pixels found in step 0
[11:20:13.697] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:20:13.791] INFO: PixTest::trimHotPixels() done
[11:20:13.791] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C0.dat
[11:20:13.799] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C1.dat
[11:20:13.804] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C2.dat
[11:20:13.809] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C3.dat
[11:20:13.814] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C4.dat
[11:20:13.820] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C5.dat
[11:20:13.825] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C6.dat
[11:20:13.830] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C7.dat
[11:20:13.835] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C8.dat
[11:20:13.840] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C9.dat
[11:20:13.845] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C10.dat
[11:20:13.850] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C11.dat
[11:20:13.856] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C12.dat
[11:20:13.861] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C13.dat
[11:20:13.866] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C14.dat
[11:20:13.871] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//trimParameters35_C15.dat
[11:20:13.876] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-O-1-22_FPIXTest-17C-FNAL-160527-0907_2016-05-27_09h08m_1464358099/000_FPIXTest_p17//defaultMaskFile.dat
[11:20:13.886] INFO: enter test to run
[11:20:45.699] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:20:45.699] INFO: running: xray
[11:20:45.701] INFO: ----------------------------------------------------------------------
[11:20:45.701] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:20:45.701] INFO: ----------------------------------------------------------------------
[11:20:46.664] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:20:58.223] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:21:27.785] INFO: Resuming triggers.
[11:21:39.348] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:22:09.564] INFO: Resuming triggers.
[11:22:21.130] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[11:22:50.359] INFO: Resuming triggers.
[11:23:01.924] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[11:23:31.157] INFO: Resuming triggers.
[11:23:42.720] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[11:24:11.598] INFO: Resuming triggers.
[11:24:23.165] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[11:24:52.433] INFO: Resuming triggers.
[11:25:03.000] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[11:25:33.640] INFO: Resuming triggers.
[11:25:45.202] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[11:26:14.567] INFO: Resuming triggers.
[11:26:22.403] INFO: data taking finished, elapsed time: 100 seconds.
[11:26:42.660] INFO: PixTest:: pg_setup set to default.
[11:26:42.664] INFO: PixTestXray::doPhRun() done
[11:26:42.833] INFO: enter test to run
[11:27:08.216] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:27:08.217] INFO: running: xray
[11:27:08.218] INFO: ----------------------------------------------------------------------
[11:27:08.218] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:27:08.218] INFO: ----------------------------------------------------------------------
[11:27:09.181] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:27:15.950] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[11:27:45.952] INFO: Resuming triggers.
[11:27:52.724] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[11:28:22.785] INFO: Resuming triggers.
[11:28:29.552] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[11:28:59.575] INFO: Resuming triggers.
[11:29:06.341] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[11:29:36.521] INFO: Resuming triggers.
[11:29:43.293] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[11:30:13.404] INFO: Resuming triggers.
[11:30:20.173] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[11:30:50.246] INFO: Resuming triggers.
[11:30:57.018] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[11:31:27.051] INFO: Resuming triggers.
[11:31:33.820] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[11:32:03.820] INFO: Resuming triggers.
[11:32:10.592] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[11:32:40.623] INFO: Resuming triggers.
[11:32:47.396] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[11:33:17.380] INFO: Resuming triggers.
[11:33:24.152] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[11:33:54.140] INFO: Resuming triggers.
[11:34:00.912] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[11:34:30.927] INFO: Resuming triggers.
[11:34:37.702] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[11:35:07.729] INFO: Resuming triggers.
[11:35:14.502] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[11:35:44.507] INFO: Resuming triggers.
[11:35:50.043] INFO: data taking finished, elapsed time: 100 seconds.
[11:36:14.890] INFO: PixTest:: pg_setup set to default.
[11:36:14.893] INFO: PixTestXray::doPhRun() done
[11:36:15.043] INFO: enter test to run
[11:36:57.988] INFO: test: HighRate no parameter change
[11:36:57.988] INFO: running: highrate
[11:36:57.989] INFO: ----------------------------------------------------------------------
[11:36:57.989] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:36:57.989] INFO: ----------------------------------------------------------------------
[11:36:58.130] INFO: Expecting 768 events.
[11:36:59.264] INFO: 768 events read in total (419ms).
[11:36:59.264] INFO: Test took 1268ms.
[11:37:00.067] INFO: Expecting 41600 events.
[11:37:03.111] INFO: 41600 events read in total (2517ms).
[11:37:03.112] INFO: Test took 3840ms.
[11:37:03.144] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:03.876] INFO: Expecting 41600 events.
[11:37:07.059] INFO: 41600 events read in total (2656ms).
[11:37:07.060] INFO: Test took 3899ms.
[11:37:07.094] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:07.817] INFO: Expecting 41600 events.
[11:37:11.022] INFO: 41600 events read in total (2678ms).
[11:37:11.023] INFO: Test took 3911ms.
[11:37:11.056] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:11.781] INFO: Expecting 41600 events.
[11:37:14.994] INFO: 41600 events read in total (2686ms).
[11:37:14.995] INFO: Test took 3922ms.
[11:37:15.029] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:15.751] INFO: Expecting 41600 events.
[11:37:18.976] INFO: 41600 events read in total (2698ms).
[11:37:18.977] INFO: Test took 3932ms.
[11:37:19.009] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:19.733] INFO: Expecting 41600 events.
[11:37:22.941] INFO: 41600 events read in total (2681ms).
[11:37:22.942] INFO: Test took 3914ms.
[11:37:22.975] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:23.704] INFO: Expecting 41600 events.
[11:37:26.921] INFO: 41600 events read in total (2691ms).
[11:37:26.922] INFO: Test took 3930ms.
[11:37:26.954] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:27.678] INFO: Expecting 41600 events.
[11:37:30.906] INFO: 41600 events read in total (2701ms).
[11:37:30.907] INFO: Test took 3934ms.
[11:37:30.939] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:31.666] INFO: Expecting 41600 events.
[11:37:34.888] INFO: 41600 events read in total (2695ms).
[11:37:34.889] INFO: Test took 3932ms.
[11:37:34.922] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:35.645] INFO: Expecting 41600 events.
[11:37:38.864] INFO: 41600 events read in total (2692ms).
[11:37:38.865] INFO: Test took 3925ms.
[11:37:38.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:39.626] INFO: Expecting 41600 events.
[11:37:42.856] INFO: 41600 events read in total (2704ms).
[11:37:42.857] INFO: Test took 3943ms.
[11:37:42.889] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:43.614] INFO: Expecting 41600 events.
[11:37:46.842] INFO: 41600 events read in total (2701ms).
[11:37:46.843] INFO: Test took 3937ms.
[11:37:46.876] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:47.600] INFO: Expecting 41600 events.
[11:37:50.817] INFO: 41600 events read in total (2690ms).
[11:37:50.818] INFO: Test took 3924ms.
[11:37:50.852] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:51.579] INFO: Expecting 41600 events.
[11:37:54.811] INFO: 41600 events read in total (2705ms).
[11:37:54.812] INFO: Test took 3942ms.
[11:37:54.846] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:55.571] INFO: Expecting 41600 events.
[11:37:58.806] INFO: 41600 events read in total (2708ms).
[11:37:58.807] INFO: Test took 3944ms.
[11:37:58.839] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:37:59.565] INFO: Expecting 41600 events.
[11:38:02.790] INFO: 41600 events read in total (2698ms).
[11:38:02.790] INFO: Test took 3934ms.
[11:38:02.823] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:03.549] INFO: Expecting 41600 events.
[11:38:06.785] INFO: 41600 events read in total (2709ms).
[11:38:06.786] INFO: Test took 3946ms.
[11:38:06.819] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:07.542] INFO: Expecting 41600 events.
[11:38:10.761] INFO: 41600 events read in total (2692ms).
[11:38:10.762] INFO: Test took 3924ms.
[11:38:10.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:11.523] INFO: Expecting 41600 events.
[11:38:14.752] INFO: 41600 events read in total (2702ms).
[11:38:14.752] INFO: Test took 3938ms.
[11:38:14.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:15.508] INFO: Expecting 41600 events.
[11:38:18.665] INFO: 41600 events read in total (2630ms).
[11:38:18.666] INFO: Test took 3864ms.
[11:38:18.698] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:19.062] INFO: enter test to run
[11:38:23.692] INFO: test: HighRate no parameter change
[11:38:23.692] INFO: running: highrate
[11:38:23.693] INFO: ----------------------------------------------------------------------
[11:38:23.693] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:38:23.693] INFO: ----------------------------------------------------------------------
[11:38:24.304] INFO: Expecting 208000 events.
[11:38:36.095] INFO: 208000 events read in total (11264ms).
[11:38:36.098] INFO: Test took 12399ms.
[11:38:36.234] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:36.483] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:38:36.483] INFO: number of red-efficiency pixels: 78 46 75 129 142 141 123 100 96 127 108 76 99 55 19 28
[11:38:36.483] INFO: number of X-ray hits detected: 61174 38955 61993 103447 116401 115087 113753 80661 78256 91033 90351 77109 79315 47924 20258 24265
[11:38:36.483] 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:38:36.483] INFO: number of Vcal hits detected: 207921 207953 207909 207870 207853 207855 207875 207898 207904 207868 207891 207924 207897 207945 207981 207972
[11:38:36.483] 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 100.0 100.0 100.0 100.0 100.0
[11:38:36.483] 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 100.0 100.0 100.0 100.0 100.0
[11:38:36.483] INFO: X-ray hit rate [MHz/cm2]: 17.9 11.4 18.2 30.3 34.1 33.7 33.3 23.6 22.9 26.7 26.5 22.6 23.2 14.0 5.9 7.1
[11:38:36.483] INFO: PixTestHighRate::doXPixelAlive() done
[11:38:36.530] INFO: PixTest:: pg_setup set to default.
[11:38:36.540] INFO: enter test to run
[11:38:57.187] INFO: test: HighRate no parameter change
[11:38:57.187] INFO: running: highrate
[11:38:57.189] INFO: ----------------------------------------------------------------------
[11:38:57.189] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:38:57.189] INFO: ----------------------------------------------------------------------
[11:38:57.806] INFO: Expecting 208000 events.
[11:39:11.357] INFO: 208000 events read in total (13025ms).
[11:39:11.362] INFO: Test took 14165ms.
[11:39:11.644] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:11.942] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:39:11.942] INFO: number of red-efficiency pixels: 227 121 194 433 562 515 459 254 261 313 350 218 244 187 52 52
[11:39:11.942] INFO: number of X-ray hits detected: 129902 82851 131632 218099 245272 241644 239168 169639 165470 191888 190017 163501 168451 101881 42892 51864
[11:39:11.942] 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:39:11.942] INFO: number of Vcal hits detected: 207765 207870 207770 207529 207381 207430 207500 207735 207729 207664 207614 207775 207742 207806 207946 207947
[11:39:11.942] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.8 99.9 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[11:39:11.942] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[11:39:11.942] INFO: X-ray hit rate [MHz/cm2]: 38.1 24.3 38.6 63.9 71.9 70.8 70.1 49.7 48.5 56.2 55.7 47.9 49.4 29.9 12.6 15.2
[11:39:11.942] INFO: PixTestHighRate::doXPixelAlive() done
[11:39:11.989] INFO: PixTest:: pg_setup set to default.
[11:39:11.002] INFO: enter test to run
[11:39:29.707] INFO: test: HighRate no parameter change
[11:39:29.707] INFO: running: highrate
[11:39:29.708] INFO: ----------------------------------------------------------------------
[11:39:29.708] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:39:29.708] INFO: ----------------------------------------------------------------------
[11:39:30.326] INFO: Expecting 208000 events.
[11:39:45.679] INFO: 208000 events read in total (14827ms).
[11:39:45.686] INFO: Test took 15969ms.
[11:39:46.106] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:46.450] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:39:46.450] INFO: number of red-efficiency pixels: 448 240 440 1046 1300 1175 1024 563 599 739 795 423 538 400 103 84
[11:39:46.450] INFO: number of X-ray hits detected: 196205 125766 198481 329613 369396 363951 360729 257071 249730 287730 286704 246734 254008 152611 64606 79175
[11:39:46.450] 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:39:46.450] INFO: number of Vcal hits detected: 207486 207735 207455 206733 206310 206477 206727 207330 207332 207147 207070 207538 207409 207541 207894 207914
[11:39:46.450] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.4 99.3 99.3 99.4 99.7 99.7 99.6 99.6 99.8 99.8 99.8 100.0 100.0
[11:39:46.450] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.4 99.2 99.3 99.4 99.7 99.7 99.6 99.6 99.8 99.7 99.8 99.9 100.0
[11:39:46.450] INFO: X-ray hit rate [MHz/cm2]: 57.5 36.9 58.2 96.6 108.3 106.7 105.7 75.3 73.2 84.3 84.0 72.3 74.5 44.7 18.9 23.2
[11:39:46.450] INFO: PixTestHighRate::doXPixelAlive() done
[11:39:46.503] INFO: PixTest:: pg_setup set to default.
[11:39:46.516] INFO: enter test to run
[11:40:03.851] INFO: test: exit no parameter change
[11:40:04.195] QUIET: Connection to board 33 closed.
[11:40:04.197] INFO: pXar: this is the end, my friend