[13:22:39.227] INFO: *** Welcome to pxar ***
[13:22:39.228] INFO: *** Today: 2016/06/22
[13:22:40.015] INFO: *** Version: v1.9.0-814-g7497
[13:22:40.015] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/dacParameters35_C15.dat
[13:22:40.072] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/tbmParameters_C0b.dat
[13:22:40.072] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/defaultMaskFile.dat
[13:22:40.073] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C15.dat
[13:22:40.223] INFO: clk: 4
[13:22:40.223] INFO: ctr: 4
[13:22:40.223] INFO: sda: 19
[13:22:40.223] INFO: tin: 9
[13:22:40.223] INFO: level: 15
[13:22:40.223] INFO: triggerdelay: 0
[13:22:40.223] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[13:22:40.223] INFO: Log level: INFO
[13:22:40.239] QUIET: Connection to board DTB_WREKRL opened.
[13:22:40.242] 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:
------------------------------------------------------
[13:22:40.245] INFO: RPC call hashes of host and DTB match: 398089610
[13:22:41.785] INFO: DUT info:
[13:22:41.785] INFO: The DUT currently contains the following objects:
[13:22:41.785] INFO: 2 TBM Cores tbm08c (2 ON)
[13:22:41.785] INFO: TBM Core alpha (0): 7 registers set
[13:22:41.785] INFO: TBM Core beta (1): 7 registers set
[13:22:41.785] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:22:41.785] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:41.785] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:41.785] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:41.785] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:41.785] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:41.785] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:41.785] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:41.785] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:41.785] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:41.785] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:41.785] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:41.786] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:41.786] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:41.786] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:41.786] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:41.786] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:22:42.226] INFO: enter 'restricted' command line mode
[13:22:42.226] INFO: enter test to run
[13:22:51.864] INFO: test: PixelAlive no parameter change
[13:22:51.864] INFO: running: pixelalive
[13:22:51.938] INFO: ----------------------------------------------------------------------
[13:22:51.938] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:22:51.938] INFO: ----------------------------------------------------------------------
[13:22:52.267] INFO: Expecting 41600 events.
[13:22:56.560] INFO: 41600 events read in total (3575ms).
[13:22:56.731] INFO: Test took 4791ms.
[13:22:56.738] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:57.305] INFO: PixTestAlive::aliveTest() done
[13:22:57.305] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:22:57.461] INFO: enter test to run
[13:23:19.600] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:23:19.600] INFO: running: highrate
[13:23:19.616] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/phCalibrationFitErr35_C15.dat
[13:23:19.938] INFO: ----------------------------------------------------------------------
[13:23:19.938] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:23:19.938] INFO: ----------------------------------------------------------------------
[13:23:19.938] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:23:19.938] INFO: edge/corner pixel THR is adjusted
[13:23:19.938] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:23:20.895] INFO: Collecting data for 5 seconds...
[13:23:25.911] INFO: Done with hot pixel readout
[13:23:37.766] INFO: PixTest:: pg_setup set to default.
[13:23:37.767] INFO: 0 hot pixels found in step 0
[13:23:37.780] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:23:37.800] INFO: PixTest::trimHotPixels() done
[13:23:37.827] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C0.dat
[13:23:37.833] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C1.dat
[13:23:37.838] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C2.dat
[13:23:37.844] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C3.dat
[13:23:37.849] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C4.dat
[13:23:37.855] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C5.dat
[13:23:37.860] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C6.dat
[13:23:37.866] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C7.dat
[13:23:37.871] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C8.dat
[13:23:37.876] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C9.dat
[13:23:37.882] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C10.dat
[13:23:37.887] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C11.dat
[13:23:37.893] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C12.dat
[13:23:37.898] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C13.dat
[13:23:37.904] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C14.dat
[13:23:37.909] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C15.dat
[13:23:37.915] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/defaultMaskFile.dat
[13:23:37.925] INFO: enter test to run
[13:24:04.168] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:24:04.168] INFO: running: highrate
[13:24:04.172] INFO: ----------------------------------------------------------------------
[13:24:04.172] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:24:04.172] INFO: ----------------------------------------------------------------------
[13:24:04.172] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:24:04.172] INFO: edge/corner pixel THR is adjusted
[13:24:04.172] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:24:05.129] INFO: Collecting data for 1 seconds...
[13:24:06.134] INFO: Done with hot pixel readout
[13:24:10.410] INFO: PixTest:: pg_setup set to default.
[13:24:10.411] INFO: 0 hot pixels found in step 0
[13:24:10.416] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:24:10.501] INFO: PixTest::trimHotPixels() done
[13:24:10.501] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C0.dat
[13:24:10.510] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C1.dat
[13:24:10.517] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C2.dat
[13:24:10.523] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C3.dat
[13:24:10.528] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C4.dat
[13:24:10.533] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C5.dat
[13:24:10.538] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C6.dat
[13:24:10.544] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C7.dat
[13:24:10.549] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C8.dat
[13:24:10.555] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C9.dat
[13:24:10.560] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C10.dat
[13:24:10.565] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C11.dat
[13:24:10.571] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C12.dat
[13:24:10.576] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C13.dat
[13:24:10.581] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C14.dat
[13:24:10.587] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/trimParameters35_C15.dat
[13:24:10.592] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-2-14_FPIXTest-17C-FNAL-160616-1221_2016-06-16_12h21m_1466097708/000_FPIXTest_p17/defaultMaskFile.dat
[13:24:10.601] INFO: enter test to run
[13:24:27.775] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:24:27.775] INFO: running: xray
[13:24:27.776] INFO: ----------------------------------------------------------------------
[13:24:27.777] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:24:27.777] INFO: ----------------------------------------------------------------------
[13:24:28.759] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:24:39.947] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:25:09.224] INFO: Resuming triggers.
[13:25:20.415] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[13:25:50.224] INFO: Resuming triggers.
[13:26:01.407] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[13:26:27.757] INFO: Resuming triggers.
[13:26:38.940] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[13:27:08.676] INFO: Resuming triggers.
[13:27:19.867] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[13:27:49.579] INFO: Resuming triggers.
[13:28:00.765] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[13:28:30.456] INFO: Resuming triggers.
[13:28:41.638] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[13:29:10.832] INFO: Resuming triggers.
[13:29:22.017] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[13:29:51.656] INFO: Resuming triggers.
[13:30:02.504] INFO: data taking finished, elapsed time: 100 seconds.
[13:30:31.399] INFO: PixTest:: pg_setup set to default.
[13:30:31.402] INFO: PixTestXray::doPhRun() done
[13:30:31.537] INFO: enter test to run
[13:34:48.475] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:34:48.475] INFO: running: xray
[13:34:48.476] INFO: ----------------------------------------------------------------------
[13:34:48.476] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:34:48.476] INFO: ----------------------------------------------------------------------
[13:34:49.448] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:34:55.851] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:35:27.057] INFO: Resuming triggers.
[13:35:33.461] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[13:36:04.857] INFO: Resuming triggers.
[13:36:11.258] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[13:36:42.759] INFO: Resuming triggers.
[13:36:49.161] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[13:37:19.603] INFO: Resuming triggers.
[13:37:26.004] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[13:37:56.603] INFO: Resuming triggers.
[13:38:03.003] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[13:38:32.223] INFO: Resuming triggers.
[13:38:38.625] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[13:39:08.523] INFO: Resuming triggers.
[13:39:14.924] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[13:39:45.221] INFO: Resuming triggers.
[13:39:51.621] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[13:40:21.218] INFO: Resuming triggers.
[13:40:27.619] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[13:40:57.826] INFO: Resuming triggers.
[13:41:04.225] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[13:41:34.402] INFO: Resuming triggers.
[13:41:40.803] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[13:42:11.122] INFO: Resuming triggers.
[13:42:17.522] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:42:47.714] INFO: Resuming triggers.
[13:42:54.113] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[13:43:24.375] INFO: Resuming triggers.
[13:43:30.777] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[13:44:01.108] INFO: Resuming triggers.
[13:44:05.424] INFO: data taking finished, elapsed time: 100 seconds.
[13:44:26.028] INFO: PixTest:: pg_setup set to default.
[13:44:26.031] INFO: PixTestXray::doPhRun() done
[13:44:26.178] INFO: enter test to run
[13:45:18.639] INFO: test: HighRate no parameter change
[13:45:18.639] INFO: running: highrate
[13:45:18.663] INFO: ----------------------------------------------------------------------
[13:45:18.663] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:45:18.663] INFO: ----------------------------------------------------------------------
[13:45:18.819] INFO: Expecting 768 events.
[13:45:19.953] INFO: 768 events read in total (419ms).
[13:45:19.953] INFO: Test took 1269ms.
[13:45:20.756] INFO: Expecting 41600 events.
[13:45:23.787] INFO: 41600 events read in total (2504ms).
[13:45:23.788] INFO: Test took 3810ms.
[13:45:23.825] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:24.536] INFO: Expecting 41600 events.
[13:45:27.685] INFO: 41600 events read in total (2623ms).
[13:45:27.686] INFO: Test took 3843ms.
[13:45:27.722] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:28.440] INFO: Expecting 41600 events.
[13:45:31.767] INFO: 41600 events read in total (2800ms).
[13:45:31.768] INFO: Test took 4028ms.
[13:45:31.805] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:32.522] INFO: Expecting 41600 events.
[13:45:35.860] INFO: 41600 events read in total (2812ms).
[13:45:35.861] INFO: Test took 4037ms.
[13:45:35.899] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:36.617] INFO: Expecting 41600 events.
[13:45:39.884] INFO: 41600 events read in total (2740ms).
[13:45:39.885] INFO: Test took 3967ms.
[13:45:39.923] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:40.642] INFO: Expecting 41600 events.
[13:45:43.730] INFO: 41600 events read in total (2561ms).
[13:45:43.731] INFO: Test took 3789ms.
[13:45:43.768] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:44.484] INFO: Expecting 41600 events.
[13:45:47.788] INFO: 41600 events read in total (2777ms).
[13:45:47.789] INFO: Test took 4002ms.
[13:45:47.827] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:48.544] INFO: Expecting 41600 events.
[13:45:51.738] INFO: 41600 events read in total (2667ms).
[13:45:51.739] INFO: Test took 3892ms.
[13:45:51.776] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:52.495] INFO: Expecting 41600 events.
[13:45:55.779] INFO: 41600 events read in total (2757ms).
[13:45:55.780] INFO: Test took 3985ms.
[13:45:55.819] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:56.534] INFO: Expecting 41600 events.
[13:45:59.632] INFO: 41600 events read in total (2571ms).
[13:45:59.633] INFO: Test took 3796ms.
[13:45:59.672] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:00.386] INFO: Expecting 41600 events.
[13:46:03.657] INFO: 41600 events read in total (2744ms).
[13:46:03.658] INFO: Test took 3967ms.
[13:46:03.696] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:04.407] INFO: Expecting 41600 events.
[13:46:07.678] INFO: 41600 events read in total (2745ms).
[13:46:07.679] INFO: Test took 3965ms.
[13:46:07.720] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:08.428] INFO: Expecting 41600 events.
[13:46:11.598] INFO: 41600 events read in total (2644ms).
[13:46:11.599] INFO: Test took 3859ms.
[13:46:11.636] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:12.356] INFO: Expecting 41600 events.
[13:46:15.596] INFO: 41600 events read in total (2713ms).
[13:46:15.597] INFO: Test took 3943ms.
[13:46:15.642] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:16.344] INFO: Expecting 41600 events.
[13:46:19.644] INFO: 41600 events read in total (2774ms).
[13:46:19.645] INFO: Test took 3985ms.
[13:46:19.682] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:20.399] INFO: Expecting 41600 events.
[13:46:23.748] INFO: 41600 events read in total (2822ms).
[13:46:23.749] INFO: Test took 4048ms.
[13:46:23.787] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:24.499] INFO: Expecting 41600 events.
[13:46:27.901] INFO: 41600 events read in total (2875ms).
[13:46:27.902] INFO: Test took 4096ms.
[13:46:27.940] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:28.654] INFO: Expecting 41600 events.
[13:46:31.699] INFO: 41600 events read in total (2518ms).
[13:46:31.700] INFO: Test took 3740ms.
[13:46:31.739] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:32.451] INFO: Expecting 41600 events.
[13:46:35.479] INFO: 41600 events read in total (2501ms).
[13:46:35.480] INFO: Test took 3719ms.
[13:46:35.518] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:36.236] INFO: Expecting 41600 events.
[13:46:39.446] INFO: 41600 events read in total (2684ms).
[13:46:39.447] INFO: Test took 3910ms.
[13:46:39.485] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:39.837] INFO: enter test to run
[13:47:27.822] INFO: test: HighRate no parameter change
[13:47:27.822] INFO: running: highrate
[13:47:27.844] INFO: ----------------------------------------------------------------------
[13:47:27.844] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:47:27.844] INFO: ----------------------------------------------------------------------
[13:47:28.471] INFO: Expecting 208000 events.
[13:47:40.655] INFO: 208000 events read in total (11657ms).
[13:47:40.658] INFO: Test took 12806ms.
[13:47:40.821] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:41.080] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:47:41.080] INFO: number of red-efficiency pixels: 79 75 99 145 165 169 154 115 106 123 129 113 119 68 32 27
[13:47:41.080] INFO: number of X-ray hits detected: 69350 47080 71490 114207 121192 124605 126826 94040 91038 109201 106565 88917 92855 55902 24167 25512
[13:47:41.080] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:47:41.080] INFO: number of Vcal hits detected: 207916 207922 207897 207851 207829 207829 207844 207885 207893 207874 207869 207886 207881 207932 207968 207973
[13:47:41.080] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[13:47:41.080] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[13:47:41.080] INFO: X-ray hit rate [MHz/cm2]: 20.3 13.8 21.0 33.5 35.5 36.5 37.2 27.6 26.7 32.0 31.2 26.1 27.2 16.4 7.1 7.5
[13:47:41.080] INFO: PixTestHighRate::doXPixelAlive() done
[13:47:41.126] INFO: PixTest:: pg_setup set to default.
[13:47:41.141] INFO: enter test to run
[13:49:29.949] INFO: test: HighRate no parameter change
[13:49:29.949] INFO: running: highrate
[13:49:29.950] INFO: ----------------------------------------------------------------------
[13:49:29.950] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:49:29.950] INFO: ----------------------------------------------------------------------
[13:49:30.582] INFO: Expecting 208000 events.
[13:49:44.282] INFO: 208000 events read in total (13174ms).
[13:49:44.288] INFO: Test took 14328ms.
[13:49:44.606] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:44.916] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:49:44.917] INFO: number of red-efficiency pixels: 249 179 322 436 606 631 499 435 265 410 461 306 344 215 73 77
[13:49:44.917] INFO: number of X-ray hits detected: 143013 98056 147293 234010 249499 257114 259429 194247 187638 224647 219975 183191 191217 115193 48752 52603
[13:49:44.917] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:49:44.917] INFO: number of Vcal hits detected: 207739 207813 207636 207538 207331 207276 207454 207506 207719 207571 207503 207678 207636 207765 207926 207922
[13:49:44.917] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.8 99.8 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[13:49:44.917] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.7 99.8 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[13:49:44.917] INFO: X-ray hit rate [MHz/cm2]: 41.9 28.7 43.2 68.6 73.1 75.4 76.0 56.9 55.0 65.8 64.5 53.7 56.0 33.8 14.3 15.4
[13:49:44.917] INFO: PixTestHighRate::doXPixelAlive() done
[13:49:44.962] INFO: PixTest:: pg_setup set to default.
[13:49:44.973] INFO: enter test to run
[13:50:43.197] INFO: test: HighRate no parameter change
[13:50:43.197] INFO: running: highrate
[13:50:43.198] INFO: ----------------------------------------------------------------------
[13:50:43.198] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:50:43.198] INFO: ----------------------------------------------------------------------
[13:50:43.814] INFO: Expecting 208000 events.
[13:50:59.537] INFO: 208000 events read in total (15196ms).
[13:50:59.545] INFO: Test took 16338ms.
[13:51:00.045] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:00.408] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:51:00.408] INFO: number of red-efficiency pixels: 493 315 704 1010 1511 1513 1115 978 585 891 989 719 797 491 109 91
[13:51:00.408] INFO: number of X-ray hits detected: 213675 146693 220269 349735 373385 385433 388857 290261 282460 336002 330417 274645 287366 174444 73350 80207
[13:51:00.408] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:51:00.408] INFO: number of Vcal hits detected: 207414 207643 207038 206731 205964 205882 206626 206710 207341 206936 206795 207170 207061 207413 207889 207909
[13:51:00.408] INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.6 99.5 99.1 99.1 99.4 99.4 99.7 99.5 99.5 99.6 99.6 99.7 100.0 100.0
[13:51:00.408] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.5 99.4 99.0 99.0 99.3 99.4 99.7 99.5 99.4 99.6 99.5 99.7 99.9 100.0
[13:51:00.408] INFO: X-ray hit rate [MHz/cm2]: 62.6 43.0 64.6 102.5 109.4 113.0 114.0 85.1 82.8 98.5 96.8 80.5 84.2 51.1 21.5 23.5
[13:51:00.408] INFO: PixTestHighRate::doXPixelAlive() done
[13:51:00.460] INFO: PixTest:: pg_setup set to default.
[13:51:00.481] INFO: enter test to run
[13:53:48.731] INFO: test: exit no parameter change
[13:53:49.126] QUIET: Connection to board 33 closed.
[13:53:49.138] INFO: pXar: this is the end, my friend