[19:42:15.229] INFO: *** Welcome to pxar ***
[19:42:15.229] INFO: *** Today: 2016/08/14
[19:42:16.057] INFO: *** Version: v1.9.0-818-g96727
[19:42:16.058] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/dacParameters35_C15.dat
[19:42:16.132] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/tbmParameters_C0b.dat
[19:42:16.132] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/defaultMaskFile.dat
[19:42:16.138] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C15.dat
[19:42:16.302] INFO: clk: 4
[19:42:16.302] INFO: ctr: 4
[19:42:16.302] INFO: sda: 19
[19:42:16.302] INFO: tin: 9
[19:42:16.302] INFO: level: 15
[19:42:16.302] INFO: triggerdelay: 0
[19:42:16.302] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[19:42:16.302] INFO: Log level: INFO
[19:42:16.335] QUIET: Connection to board DTB_WREKRL opened.
[19:42:16.339] 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:
------------------------------------------------------
[19:42:16.341] INFO: RPC call hashes of host and DTB match: 398089610
[19:42:17.883] INFO: DUT info:
[19:42:17.883] INFO: The DUT currently contains the following objects:
[19:42:17.883] INFO: 2 TBM Cores tbm08c (2 ON)
[19:42:17.883] INFO: TBM Core alpha (0): 7 registers set
[19:42:17.883] INFO: TBM Core beta (1): 7 registers set
[19:42:17.883] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[19:42:17.884] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:17.884] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:17.884] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:17.884] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:17.884] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:17.884] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:17.884] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:17.884] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:17.884] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:17.884] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:17.884] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:17.884] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:17.884] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:17.884] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:17.884] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:17.884] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[19:42:18.311] INFO: enter 'restricted' command line mode
[19:42:18.311] INFO: enter test to run
[19:42:18.311] INFO: test: y no parameter change
[19:42:18.311] INFO: running: y
[19:42:18.311] INFO: command ->y<- not known, ignored
[19:42:18.311] INFO: enter test to run
[19:42:24.756] INFO: test: PixelAlive no parameter change
[19:42:24.756] INFO: running: pixelalive
[19:42:24.814] INFO: ----------------------------------------------------------------------
[19:42:24.814] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[19:42:24.814] INFO: ----------------------------------------------------------------------
[19:42:25.145] INFO: Expecting 41600 events.
[19:42:29.415] INFO: 41600 events read in total (3551ms).
[19:42:29.582] INFO: Test took 4765ms.
[19:42:29.596] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:42:30.100] INFO: PixTestAlive::aliveTest() done
[19:42:30.100] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 4 2 0 0 0
[19:42:30.255] INFO: enter test to run
[19:43:43.907] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[19:43:43.907] INFO: running: highrate
[19:43:43.918] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/phCalibrationFitErr35_C15.dat
[19:43:44.199] INFO: ----------------------------------------------------------------------
[19:43:44.200] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[19:43:44.200] INFO: ----------------------------------------------------------------------
[19:43:44.200] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[19:43:44.200] INFO: edge/corner pixel THR is adjusted
[19:43:44.200] INFO: PixTestHighRate::trimHotPixels: step 0...
[19:43:45.160] INFO: Collecting data for 5 seconds...
[19:43:50.177] INFO: Done with hot pixel readout
[19:44:01.123] INFO: PixTest:: pg_setup set to default.
[19:44:01.124] INFO: 0 hot pixels found in step 0
[19:44:01.136] INFO: 0 hot pixels could not be trimmed and have been masked.
[19:44:01.159] INFO: PixTest::trimHotPixels() done
[19:44:01.174] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C0.dat
[19:44:01.179] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C1.dat
[19:44:01.185] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C2.dat
[19:44:01.190] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C3.dat
[19:44:01.195] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C4.dat
[19:44:01.200] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C5.dat
[19:44:01.206] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C6.dat
[19:44:01.211] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C7.dat
[19:44:01.216] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C8.dat
[19:44:01.221] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C9.dat
[19:44:01.227] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C10.dat
[19:44:01.232] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C11.dat
[19:44:01.237] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C12.dat
[19:44:01.242] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C13.dat
[19:44:01.248] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C14.dat
[19:44:01.253] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C15.dat
[19:44:01.258] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/defaultMaskFile.dat
[19:44:01.269] INFO: enter test to run
[19:44:18.259] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[19:44:18.259] INFO: running: highrate
[19:44:18.263] INFO: ----------------------------------------------------------------------
[19:44:18.264] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[19:44:18.264] INFO: ----------------------------------------------------------------------
[19:44:18.264] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[19:44:18.264] INFO: edge/corner pixel THR is adjusted
[19:44:18.264] INFO: PixTestHighRate::trimHotPixels: step 0...
[19:44:19.222] INFO: Collecting data for 1 seconds...
[19:44:20.226] INFO: Done with hot pixel readout
[19:44:23.754] INFO: PixTest:: pg_setup set to default.
[19:44:23.754] INFO: 0 hot pixels found in step 0
[19:44:23.760] INFO: 0 hot pixels could not be trimmed and have been masked.
[19:44:23.868] INFO: PixTest::trimHotPixels() done
[19:44:23.869] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C0.dat
[19:44:23.881] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C1.dat
[19:44:23.887] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C2.dat
[19:44:23.892] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C3.dat
[19:44:23.897] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C4.dat
[19:44:23.903] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C5.dat
[19:44:23.908] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C6.dat
[19:44:23.913] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C7.dat
[19:44:23.918] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C8.dat
[19:44:23.923] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C9.dat
[19:44:23.928] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C10.dat
[19:44:23.934] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C11.dat
[19:44:23.939] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C12.dat
[19:44:23.944] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C13.dat
[19:44:23.949] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C14.dat
[19:44:23.954] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/trimParameters35_C15.dat
[19:44:23.960] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-2-07_FPIXTest-17C-FNAL-160811-1113_2016-08-11_11h13m_1470932004/000_FPIXTest_p17/defaultMaskFile.dat
[19:44:23.969] INFO: enter test to run
[19:44:41.123] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[19:44:41.123] INFO: running: xray
[19:44:41.124] INFO: ----------------------------------------------------------------------
[19:44:41.124] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[19:44:41.124] INFO: ----------------------------------------------------------------------
[19:44:42.103] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[19:44:54.504] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[19:45:23.514] INFO: Resuming triggers.
[19:45:35.919] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[19:46:05.030] INFO: Resuming triggers.
[19:46:17.432] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[19:46:46.488] INFO: Resuming triggers.
[19:46:58.889] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[19:47:27.906] INFO: Resuming triggers.
[19:47:40.307] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[19:48:09.307] INFO: Resuming triggers.
[19:48:21.707] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[19:48:50.750] INFO: Resuming triggers.
[19:49:03.154] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[19:49:32.107] INFO: Resuming triggers.
[19:49:44.508] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[19:50:13.652] INFO: Resuming triggers.
[19:50:14.773] INFO: data taking finished, elapsed time: 100 seconds.
[19:50:17.769] INFO: PixTest:: pg_setup set to default.
[19:50:17.773] INFO: PixTestXray::doPhRun() done
[19:50:17.908] INFO: enter test to run
[19:50:46.408] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[19:50:46.408] INFO: running: xray
[19:50:46.409] INFO: ----------------------------------------------------------------------
[19:50:46.409] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[19:50:46.410] INFO: ----------------------------------------------------------------------
[19:50:47.372] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[19:50:55.224] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[19:51:25.861] INFO: Resuming triggers.
[19:51:33.715] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[19:52:04.121] INFO: Resuming triggers.
[19:52:11.974] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[19:52:42.705] INFO: Resuming triggers.
[19:52:50.555] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[19:53:21.988] INFO: Resuming triggers.
[19:53:29.837] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[19:54:01.053] INFO: Resuming triggers.
[19:54:08.904] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[19:54:40.219] INFO: Resuming triggers.
[19:54:48.067] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[19:55:18.040] INFO: Resuming triggers.
[19:55:25.890] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[19:55:55.885] INFO: Resuming triggers.
[19:56:03.738] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[19:56:33.694] INFO: Resuming triggers.
[19:56:41.545] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[19:57:11.514] INFO: Resuming triggers.
[19:57:19.365] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[19:57:49.398] INFO: Resuming triggers.
[19:57:57.249] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[19:58:27.318] INFO: Resuming triggers.
[19:58:33.463] INFO: data taking finished, elapsed time: 100 seconds.
[19:58:57.232] INFO: PixTest:: pg_setup set to default.
[19:58:57.235] INFO: PixTestXray::doPhRun() done
[19:58:57.384] INFO: enter test to run
[20:11:03.272] INFO: test: HighRate no parameter change
[20:11:03.272] INFO: running: highrate
[20:11:03.273] INFO: ----------------------------------------------------------------------
[20:11:03.273] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[20:11:03.273] INFO: ----------------------------------------------------------------------
[20:11:03.414] INFO: Expecting 768 events.
[20:11:04.548] INFO: 768 events read in total (419ms).
[20:11:04.548] INFO: Test took 1268ms.
[20:11:05.351] INFO: Expecting 41600 events.
[20:11:08.332] INFO: 41600 events read in total (2454ms).
[20:11:08.333] INFO: Test took 3776ms.
[20:11:08.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:11:09.101] INFO: Expecting 41600 events.
[20:11:12.185] INFO: 41600 events read in total (2557ms).
[20:11:12.186] INFO: Test took 3811ms.
[20:11:12.214] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:11:12.950] INFO: Expecting 41600 events.
[20:11:16.133] INFO: 41600 events read in total (2656ms).
[20:11:16.134] INFO: Test took 3905ms.
[20:11:16.162] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:11:16.895] INFO: Expecting 41600 events.
[20:11:20.071] INFO: 41600 events read in total (2649ms).
[20:11:20.072] INFO: Test took 3893ms.
[20:11:20.100] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:11:20.835] INFO: Expecting 41600 events.
[20:11:24.009] INFO: 41600 events read in total (2647ms).
[20:11:24.009] INFO: Test took 3892ms.
[20:11:24.038] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:11:24.774] INFO: Expecting 41600 events.
[20:11:27.956] INFO: 41600 events read in total (2655ms).
[20:11:27.957] INFO: Test took 3903ms.
[20:11:27.986] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:11:28.721] INFO: Expecting 41600 events.
[20:11:31.904] INFO: 41600 events read in total (2656ms).
[20:11:31.905] INFO: Test took 3903ms.
[20:11:31.933] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:11:32.669] INFO: Expecting 41600 events.
[20:11:35.851] INFO: 41600 events read in total (2655ms).
[20:11:35.852] INFO: Test took 3903ms.
[20:11:35.881] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:11:36.614] INFO: Expecting 41600 events.
[20:11:39.789] INFO: 41600 events read in total (2648ms).
[20:11:39.790] INFO: Test took 3892ms.
[20:11:39.818] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:11:40.555] INFO: Expecting 41600 events.
[20:11:43.738] INFO: 41600 events read in total (2656ms).
[20:11:43.739] INFO: Test took 3904ms.
[20:11:43.767] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:11:44.504] INFO: Expecting 41600 events.
[20:11:47.685] INFO: 41600 events read in total (2655ms).
[20:11:47.686] INFO: Test took 3902ms.
[20:11:47.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:11:48.450] INFO: Expecting 41600 events.
[20:11:51.622] INFO: 41600 events read in total (2646ms).
[20:11:51.623] INFO: Test took 3894ms.
[20:11:51.652] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:11:52.388] INFO: Expecting 41600 events.
[20:11:55.588] INFO: 41600 events read in total (2673ms).
[20:11:55.589] INFO: Test took 3920ms.
[20:11:55.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:11:56.346] INFO: Expecting 41600 events.
[20:11:59.518] INFO: 41600 events read in total (2645ms).
[20:11:59.519] INFO: Test took 3887ms.
[20:11:59.547] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:12:00.283] INFO: Expecting 41600 events.
[20:12:03.461] INFO: 41600 events read in total (2652ms).
[20:12:03.462] INFO: Test took 3898ms.
[20:12:03.490] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:12:04.223] INFO: Expecting 41600 events.
[20:12:07.399] INFO: 41600 events read in total (2650ms).
[20:12:07.400] INFO: Test took 3894ms.
[20:12:07.429] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:12:08.164] INFO: Expecting 41600 events.
[20:12:11.350] INFO: 41600 events read in total (2659ms).
[20:12:11.351] INFO: Test took 3905ms.
[20:12:11.380] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:12:12.117] INFO: Expecting 41600 events.
[20:12:15.286] INFO: 41600 events read in total (2643ms).
[20:12:15.286] INFO: Test took 3890ms.
[20:12:15.314] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:12:16.051] INFO: Expecting 41600 events.
[20:12:19.205] INFO: 41600 events read in total (2627ms).
[20:12:19.206] INFO: Test took 3875ms.
[20:12:19.234] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:12:19.967] INFO: Expecting 41600 events.
[20:12:23.020] INFO: 41600 events read in total (2527ms).
[20:12:23.021] INFO: Test took 3770ms.
[20:12:23.049] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:12:23.423] INFO: enter test to run
[20:12:43.151] INFO: test: HighRate no parameter change
[20:12:43.151] INFO: running: highrate
[20:12:43.153] INFO: ----------------------------------------------------------------------
[20:12:43.153] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[20:12:43.153] INFO: ----------------------------------------------------------------------
[20:12:43.773] INFO: Expecting 208000 events.
[20:12:55.283] INFO: 208000 events read in total (10983ms).
[20:12:55.286] INFO: Test took 12122ms.
[20:12:55.402] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:12:55.646] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 4 2 0 0 0
[20:12:55.646] INFO: number of red-efficiency pixels: 62 44 51 89 96 112 104 70 54 78 77 61 63 61 8 24
[20:12:55.646] INFO: number of X-ray hits detected: 52784 34126 52885 81310 91540 95245 92623 67698 61486 71752 77650 63951 67416 40797 15615 19925
[20:12:55.646] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[20:12:55.646] INFO: number of Vcal hits detected: 207938 207956 207949 207903 207904 207888 207895 207930 207945 207921 207922 207741 207836 207939 207992 207976
[20:12:55.646] INFO: Vcal hit fiducial efficiency (%): 100.0 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
[20:12:55.646] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 99.9 99.9 100.0 100.0 100.0
[20:12:55.646] INFO: X-ray hit rate [MHz/cm2]: 15.5 10.0 15.5 23.8 26.8 27.9 27.1 19.8 18.0 21.0 22.8 18.7 19.8 12.0 4.6 5.8
[20:12:55.646] INFO: PixTestHighRate::doXPixelAlive() done
[20:12:55.699] INFO: PixTest:: pg_setup set to default.
[20:12:55.709] INFO: enter test to run
[20:13:35.263] INFO: test: HighRate no parameter change
[20:13:35.263] INFO: running: highrate
[20:13:35.264] INFO: ----------------------------------------------------------------------
[20:13:35.264] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[20:13:35.264] INFO: ----------------------------------------------------------------------
[20:13:35.885] INFO: Expecting 208000 events.
[20:13:48.670] INFO: 208000 events read in total (12258ms).
[20:13:48.675] INFO: Test took 13401ms.
[20:13:48.894] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:13:49.172] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 4 2 0 0 0
[20:13:49.172] INFO: number of red-efficiency pixels: 156 82 171 205 259 296 277 181 175 169 196 168 181 125 32 50
[20:13:49.173] INFO: number of X-ray hits detected: 105434 68295 106138 162759 183038 189383 184885 135582 122232 141711 155642 128462 135459 81267 31456 40035
[20:13:49.173] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[20:13:49.173] INFO: number of Vcal hits detected: 207833 207917 207820 207771 207729 207690 207715 207813 207816 207825 207800 207633 207714 207870 207967 207948
[20:13:49.173] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[20:13:49.173] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 100.0 100.0
[20:13:49.173] INFO: X-ray hit rate [MHz/cm2]: 30.9 20.0 31.1 47.7 53.6 55.5 54.2 39.7 35.8 41.5 45.6 37.7 39.7 23.8 9.2 11.7
[20:13:49.173] INFO: PixTestHighRate::doXPixelAlive() done
[20:13:49.225] INFO: PixTest:: pg_setup set to default.
[20:13:49.240] INFO: enter test to run
[20:14:06.783] INFO: test: HighRate no parameter change
[20:14:06.783] INFO: running: highrate
[20:14:06.784] INFO: ----------------------------------------------------------------------
[20:14:06.784] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[20:14:06.784] INFO: ----------------------------------------------------------------------
[20:14:07.399] INFO: Expecting 208000 events.
[20:14:21.452] INFO: 208000 events read in total (13526ms).
[20:14:21.457] INFO: Test took 14663ms.
[20:14:21.783] INFO: Fetched DAQ statistics. Counters are being reset now.
[20:14:22.098] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 4 2 0 0 0
[20:14:22.098] INFO: number of red-efficiency pixels: 318 165 351 420 561 676 569 385 323 313 404 329 389 242 55 89
[20:14:22.098] INFO: number of X-ray hits detected: 156845 101917 159198 241426 272570 284328 276788 202421 183954 213513 231414 192736 203683 121923 46363 59963
[20:14:22.098] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[20:14:22.098] INFO: number of Vcal hits detected: 207637 207825 207594 207529 207391 207217 207378 207586 207653 207665 207567 207454 207481 207742 207943 207906
[20:14:22.098] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.8 99.7 99.7 99.7 99.8 99.8 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[20:14:22.098] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.8 99.7 99.6 99.7 99.8 99.8 99.8 99.8 99.7 99.8 99.9 100.0 100.0
[20:14:22.098] INFO: X-ray hit rate [MHz/cm2]: 46.0 29.9 46.7 70.8 79.9 83.3 81.1 59.3 53.9 62.6 67.8 56.5 59.7 35.7 13.6 17.6
[20:14:22.098] INFO: PixTestHighRate::doXPixelAlive() done
[20:14:22.149] INFO: PixTest:: pg_setup set to default.
[20:14:22.167] INFO: enter test to run
[20:14:53.326] INFO: test: exit no parameter change
[20:14:53.640] QUIET: Connection to board 33 closed.
[20:14:53.641] INFO: pXar: this is the end, my friend