[10:20:22.636] INFO: *** Welcome to pxar ***
[10:20:22.636] INFO: *** Today: 2016/04/20
[10:20:22.655] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:20:22.655] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/dacParameters35_C15.dat
[10:20:22.682] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/tbmParameters_C0b.dat
[10:20:22.682] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/defaultMaskFile.dat
[10:20:22.682] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C15.dat
[10:20:22.761] INFO: clk: 4
[10:20:22.761] INFO: ctr: 4
[10:20:22.761] INFO: sda: 19
[10:20:22.761] INFO: tin: 9
[10:20:22.761] INFO: level: 15
[10:20:22.761] INFO: triggerdelay: 0
[10:20:22.761] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:20:22.761] INFO: Log level: INFO
[10:20:22.779] QUIET: Connection to board DTB_WREKRL opened.
[10:20:22.783] 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:
------------------------------------------------------
[10:20:22.785] INFO: RPC call hashes of host and DTB match: 398089610
[10:20:24.316] INFO: DUT info:
[10:20:24.316] INFO: The DUT currently contains the following objects:
[10:20:24.316] INFO: 2 TBM Cores tbm08c (2 ON)
[10:20:24.316] INFO: TBM Core alpha (0): 7 registers set
[10:20:24.316] INFO: TBM Core beta (1): 7 registers set
[10:20:24.316] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:20:24.316] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.316] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.316] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.316] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.317] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.317] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.317] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.317] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.317] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.317] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.317] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.317] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.317] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.317] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.317] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.317] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:24.720] INFO: enter 'restricted' command line mode
[10:20:24.721] INFO: enter test to run
[10:20:32.346] INFO: test: PixelAlive no parameter change
[10:20:32.347] INFO: running: pixelalive
[10:20:32.356] INFO: ----------------------------------------------------------------------
[10:20:32.356] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:20:32.356] INFO: ----------------------------------------------------------------------
[10:20:32.674] INFO: Expecting 41600 events.
[10:20:37.014] INFO: 41600 events read in total (3622ms).
[10:20:37.180] INFO: Test took 4822ms.
[10:20:37.194] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:37.458] INFO: PixTestAlive::aliveTest() done
[10:20:37.458] INFO: number of dead pixels (per ROC): 0 0 0 0 9 0 79 0 0 0 2 0 2 0 0 0
[10:20:37.491] INFO: enter test to run
[10:21:41.066] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:21:41.066] INFO: running: highrate
[10:21:41.066] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/phCalibrationFitErr35_C15.dat
[10:21:41.322] INFO: ----------------------------------------------------------------------
[10:21:41.322] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:21:41.322] INFO: ----------------------------------------------------------------------
[10:21:41.322] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:21:41.322] INFO: edge/corner pixel THR is adjusted
[10:21:41.322] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:21:42.282] INFO: Collecting data for 5 seconds...
[10:21:47.299] INFO: Done with hot pixel readout
[10:21:59.322] INFO: PixTest:: pg_setup set to default.
[10:21:59.323] INFO: 2 hot pixels found in step 0
[10:22:00.338] INFO: Collecting data for 5 seconds...
[10:22:05.354] INFO: Done with hot pixel readout
[10:22:17.349] INFO: PixTest:: pg_setup set to default.
[10:22:17.349] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:22:17.350] INFO: 3 hot pixels found in step 1
[10:22:18.346] INFO: Collecting data for 5 seconds...
[10:22:23.362] INFO: Done with hot pixel readout
[10:22:35.384] INFO: PixTest:: pg_setup set to default.
[10:22:35.385] INFO: 4 hot pixels found in step 2
[10:22:36.379] INFO: Collecting data for 5 seconds...
[10:22:41.395] INFO: Done with hot pixel readout
[10:22:53.375] INFO: PixTest:: pg_setup set to default.
[10:22:53.375] INFO: 5 hot pixels found in step 3
[10:22:54.370] INFO: Collecting data for 5 seconds...
[10:22:59.386] INFO: Done with hot pixel readout
[10:23:11.397] INFO: PixTest:: pg_setup set to default.
[10:23:11.398] INFO: 7 hot pixels found in step 4
[10:23:12.391] INFO: Collecting data for 5 seconds...
[10:23:17.408] INFO: Done with hot pixel readout
[10:23:29.444] INFO: PixTest:: pg_setup set to default.
[10:23:29.444] INFO: 3 hot pixels found in step 5
[10:23:30.438] INFO: Collecting data for 5 seconds...
[10:23:35.456] INFO: Done with hot pixel readout
[10:23:47.376] INFO: PixTest:: pg_setup set to default.
[10:23:47.377] INFO: 3 hot pixels found in step 6
[10:23:48.372] INFO: Collecting data for 5 seconds...
[10:23:53.388] INFO: Done with hot pixel readout
[10:24:05.388] INFO: PixTest:: pg_setup set to default.
[10:24:05.389] INFO: 3 hot pixels found in step 7
[10:24:06.383] INFO: Collecting data for 5 seconds...
[10:24:11.399] INFO: Done with hot pixel readout
[10:24:23.445] INFO: PixTest:: pg_setup set to default.
[10:24:23.446] INFO: 2 hot pixels found in step 8
[10:24:24.440] INFO: Collecting data for 5 seconds...
[10:24:29.457] INFO: Done with hot pixel readout
[10:24:41.468] INFO: PixTest:: pg_setup set to default.
[10:24:41.468] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:41.469] INFO: 4 hot pixels found in step 9
[10:24:42.463] INFO: Collecting data for 5 seconds...
[10:24:47.479] INFO: Done with hot pixel readout
[10:24:59.492] INFO: PixTest:: pg_setup set to default.
[10:24:59.492] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:59.493] INFO: 4 hot pixels found in step 10
[10:25:00.487] INFO: Collecting data for 5 seconds...
[10:25:05.504] INFO: Done with hot pixel readout
[10:25:17.515] INFO: PixTest:: pg_setup set to default.
[10:25:17.516] INFO: 4 hot pixels found in step 11
[10:25:18.510] INFO: Collecting data for 5 seconds...
[10:25:23.526] INFO: Done with hot pixel readout
[10:25:35.587] INFO: PixTest:: pg_setup set to default.
[10:25:35.588] INFO: 3 hot pixels found in step 12
[10:25:36.582] INFO: Collecting data for 5 seconds...
[10:25:41.599] INFO: Done with hot pixel readout
[10:25:53.580] INFO: PixTest:: pg_setup set to default.
[10:25:53.580] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:53.581] INFO: 3 hot pixels found in step 13
[10:25:54.575] INFO: Collecting data for 5 seconds...
[10:25:59.591] INFO: Done with hot pixel readout
[10:26:11.603] INFO: PixTest:: pg_setup set to default.
[10:26:11.604] INFO: 3 hot pixels found in step 14
[10:26:11.641] INFO: 3 hot pixels could not be trimmed and have been masked.
[10:26:11.645] INFO: PixTest::trimHotPixels() done
[10:26:11.645] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C0.dat
[10:26:11.651] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C1.dat
[10:26:11.657] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C2.dat
[10:26:11.663] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C3.dat
[10:26:11.668] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C4.dat
[10:26:11.673] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C5.dat
[10:26:11.678] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C6.dat
[10:26:11.683] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C7.dat
[10:26:11.688] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C8.dat
[10:26:11.693] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C9.dat
[10:26:11.698] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C10.dat
[10:26:11.704] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C11.dat
[10:26:11.709] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C12.dat
[10:26:11.714] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C13.dat
[10:26:11.719] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C14.dat
[10:26:11.724] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C15.dat
[10:26:11.729] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/defaultMaskFile.dat
[10:26:11.739] INFO: enter test to run
[10:26:35.207] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:26:35.208] INFO: running: highrate
[10:26:35.212] INFO: ----------------------------------------------------------------------
[10:26:35.212] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:26:35.212] INFO: ----------------------------------------------------------------------
[10:26:35.212] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:26:35.212] INFO: edge/corner pixel THR is adjusted
[10:26:35.212] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:26:36.169] INFO: Collecting data for 1 seconds...
[10:26:37.173] INFO: Done with hot pixel readout
[10:26:41.338] INFO: PixTest:: pg_setup set to default.
[10:26:41.339] INFO: 0 hot pixels found in step 0
[10:26:41.344] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:26:41.430] INFO: PixTest::trimHotPixels() done
[10:26:41.430] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C0.dat
[10:26:41.441] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C1.dat
[10:26:41.447] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C2.dat
[10:26:41.452] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C3.dat
[10:26:41.457] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C4.dat
[10:26:41.462] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C5.dat
[10:26:41.467] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C6.dat
[10:26:41.473] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C7.dat
[10:26:41.478] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C8.dat
[10:26:41.483] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C9.dat
[10:26:41.488] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C10.dat
[10:26:41.493] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C11.dat
[10:26:41.498] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C12.dat
[10:26:41.503] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C13.dat
[10:26:41.509] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C14.dat
[10:26:41.514] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/trimParameters35_C15.dat
[10:26:41.519] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-L-1-36_FPIXTest-17C-FNAL-160414-0854_2016-04-14_08h54m_1460642076/000_FPIXTest_p17/defaultMaskFile.dat
[10:26:41.536] INFO: enter test to run
[10:26:59.799] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:26:59.799] INFO: running: xray
[10:26:59.801] INFO: ----------------------------------------------------------------------
[10:26:59.801] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:26:59.801] INFO: ----------------------------------------------------------------------
[10:27:00.763] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:27:11.906] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:27:41.475] INFO: Resuming triggers.
[10:27:52.620] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[10:28:20.186] INFO: Resuming triggers.
[10:28:31.328] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:29:00.903] INFO: Resuming triggers.
[10:29:12.047] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[10:29:41.673] INFO: Resuming triggers.
[10:29:52.815] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[10:30:22.365] INFO: Resuming triggers.
[10:30:33.504] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[10:31:03.106] INFO: Resuming triggers.
[10:31:14.248] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[10:31:43.905] INFO: Resuming triggers.
[10:31:55.046] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[10:32:24.700] INFO: Resuming triggers.
[10:32:35.839] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[10:33:05.510] INFO: Resuming triggers.
[10:33:05.556] INFO: data taking finished, elapsed time: 100 seconds.
[10:33:06.235] INFO: PixTest:: pg_setup set to default.
[10:33:06.238] INFO: PixTestXray::doPhRun() done
[10:33:06.376] INFO: enter test to run
[10:33:26.228] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:33:26.229] INFO: running: xray
[10:33:26.230] INFO: ----------------------------------------------------------------------
[10:33:26.230] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:33:26.230] INFO: ----------------------------------------------------------------------
[10:33:27.202] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:33:33.541] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:34:03.730] INFO: Resuming triggers.
[10:34:10.068] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[10:34:40.173] INFO: Resuming triggers.
[10:34:46.515] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[10:35:16.639] INFO: Resuming triggers.
[10:35:22.978] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[10:35:53.177] INFO: Resuming triggers.
[10:35:59.517] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[10:36:29.764] INFO: Resuming triggers.
[10:36:36.105] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[10:37:06.332] INFO: Resuming triggers.
[10:37:12.672] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[10:37:42.820] INFO: Resuming triggers.
[10:37:49.163] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[10:38:19.582] INFO: Resuming triggers.
[10:38:25.925] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[10:38:54.590] INFO: Resuming triggers.
[10:39:00.932] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[10:39:31.091] INFO: Resuming triggers.
[10:39:37.433] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:40:07.575] INFO: Resuming triggers.
[10:40:13.915] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[10:40:44.163] INFO: Resuming triggers.
[10:40:50.506] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[10:41:20.654] INFO: Resuming triggers.
[10:41:26.994] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[10:41:57.249] INFO: Resuming triggers.
[10:42:03.589] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[10:42:33.843] INFO: Resuming triggers.
[10:42:39.052] INFO: data taking finished, elapsed time: 100 seconds.
[10:43:04.054] INFO: PixTest:: pg_setup set to default.
[10:43:04.057] INFO: PixTestXray::doPhRun() done
[10:43:04.208] INFO: enter test to run
[10:43:44.400] INFO: test: HighRate no parameter change
[10:43:44.400] INFO: running: highrate
[10:43:44.401] INFO: ----------------------------------------------------------------------
[10:43:44.401] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:43:44.401] INFO: ----------------------------------------------------------------------
[10:43:44.542] INFO: Expecting 768 events.
[10:43:45.676] INFO: 768 events read in total (418ms).
[10:43:45.677] INFO: Test took 1270ms.
[10:43:46.480] INFO: Expecting 41600 events.
[10:43:49.512] INFO: 41600 events read in total (2505ms).
[10:43:49.513] INFO: Test took 3829ms.
[10:43:49.547] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:50.276] INFO: Expecting 41600 events.
[10:43:53.463] INFO: 41600 events read in total (2661ms).
[10:43:53.464] INFO: Test took 3899ms.
[10:43:53.499] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:54.220] INFO: Expecting 41600 events.
[10:43:57.451] INFO: 41600 events read in total (2704ms).
[10:43:57.452] INFO: Test took 3935ms.
[10:43:57.487] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:58.210] INFO: Expecting 41600 events.
[10:44:01.515] INFO: 41600 events read in total (2778ms).
[10:44:01.516] INFO: Test took 4010ms.
[10:44:01.553] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:02.271] INFO: Expecting 41600 events.
[10:44:05.532] INFO: 41600 events read in total (2734ms).
[10:44:05.533] INFO: Test took 3963ms.
[10:44:05.569] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:06.284] INFO: Expecting 41600 events.
[10:44:09.533] INFO: 41600 events read in total (2722ms).
[10:44:09.534] INFO: Test took 3946ms.
[10:44:09.569] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:10.290] INFO: Expecting 41600 events.
[10:44:13.571] INFO: 41600 events read in total (2754ms).
[10:44:13.572] INFO: Test took 3984ms.
[10:44:13.608] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:14.320] INFO: Expecting 41600 events.
[10:44:17.584] INFO: 41600 events read in total (2737ms).
[10:44:17.585] INFO: Test took 3958ms.
[10:44:17.622] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:18.341] INFO: Expecting 41600 events.
[10:44:21.593] INFO: 41600 events read in total (2726ms).
[10:44:21.594] INFO: Test took 3953ms.
[10:44:21.629] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:22.350] INFO: Expecting 41600 events.
[10:44:25.601] INFO: 41600 events read in total (2724ms).
[10:44:25.602] INFO: Test took 3954ms.
[10:44:25.637] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:26.360] INFO: Expecting 41600 events.
[10:44:29.617] INFO: 41600 events read in total (2730ms).
[10:44:29.618] INFO: Test took 3963ms.
[10:44:29.653] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:30.373] INFO: Expecting 41600 events.
[10:44:33.626] INFO: 41600 events read in total (2726ms).
[10:44:33.627] INFO: Test took 3955ms.
[10:44:33.663] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:34.381] INFO: Expecting 41600 events.
[10:44:37.625] INFO: 41600 events read in total (2717ms).
[10:44:37.626] INFO: Test took 3945ms.
[10:44:37.661] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:38.382] INFO: Expecting 41600 events.
[10:44:41.642] INFO: 41600 events read in total (2733ms).
[10:44:41.643] INFO: Test took 3965ms.
[10:44:41.678] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:42.397] INFO: Expecting 41600 events.
[10:44:45.677] INFO: 41600 events read in total (2753ms).
[10:44:45.678] INFO: Test took 3981ms.
[10:44:45.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:46.433] INFO: Expecting 41600 events.
[10:44:49.695] INFO: 41600 events read in total (2735ms).
[10:44:49.696] INFO: Test took 3964ms.
[10:44:49.731] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:50.449] INFO: Expecting 41600 events.
[10:44:53.711] INFO: 41600 events read in total (2735ms).
[10:44:53.712] INFO: Test took 3961ms.
[10:44:53.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:54.460] INFO: Expecting 41600 events.
[10:44:57.702] INFO: 41600 events read in total (2715ms).
[10:44:57.703] INFO: Test took 3936ms.
[10:44:57.739] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:58.462] INFO: Expecting 41600 events.
[10:45:01.709] INFO: 41600 events read in total (2720ms).
[10:45:01.710] INFO: Test took 3952ms.
[10:45:01.750] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:02.468] INFO: Expecting 41600 events.
[10:45:05.630] INFO: 41600 events read in total (2635ms).
[10:45:05.631] INFO: Test took 3862ms.
[10:45:05.666] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:06.026] INFO: enter test to run
[10:45:32.951] INFO: test: HighRate no parameter change
[10:45:32.951] INFO: running: highrate
[10:45:32.952] INFO: ----------------------------------------------------------------------
[10:45:32.952] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:45:32.952] INFO: ----------------------------------------------------------------------
[10:45:33.571] INFO: Expecting 208000 events.
[10:45:45.534] INFO: 208000 events read in total (11436ms).
[10:45:45.538] INFO: Test took 12578ms.
[10:45:45.690] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:45.944] INFO: number of dead pixels (per ROC): 0 0 0 0 9 0 80 0 0 0 2 0 2 0 0 0
[10:45:45.944] INFO: number of red-efficiency pixels: 87 54 79 156 181 147 237 109 108 133 100 132 107 78 26 26
[10:45:45.944] INFO: number of X-ray hits detected: 68093 45032 67659 112001 118643 126388 119374 89057 81020 104013 96353 89685 89289 54859 23681 25050
[10:45:45.944] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:45:45.944] INFO: number of Vcal hits detected: 207911 207944 207916 207795 207369 207844 203837 207888 207891 207865 207799 207867 207793 207922 207974 207973
[10:45:45.944] 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 100.0 99.9 100.0 100.0 100.0 100.0
[10:45:45.944] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.7 99.9 98.0 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[10:45:45.944] INFO: X-ray hit rate [MHz/cm2]: 20.0 13.2 19.8 32.8 34.8 37.0 35.0 26.1 23.7 30.5 28.2 26.3 26.2 16.1 6.9 7.3
[10:45:45.944] INFO: PixTestHighRate::doXPixelAlive() done
[10:45:45.992] INFO: PixTest:: pg_setup set to default.
[10:45:45.005] INFO: enter test to run
[10:45:59.775] INFO: test: HighRate no parameter change
[10:45:59.775] INFO: running: highrate
[10:45:59.776] INFO: ----------------------------------------------------------------------
[10:45:59.776] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:45:59.776] INFO: ----------------------------------------------------------------------
[10:46:00.394] INFO: Expecting 208000 events.
[10:46:14.435] INFO: 208000 events read in total (13514ms).
[10:46:14.441] INFO: Test took 14657ms.
[10:46:14.762] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:15.073] INFO: number of dead pixels (per ROC): 0 0 0 0 9 0 79 0 0 0 2 0 2 0 0 0
[10:46:15.073] INFO: number of red-efficiency pixels: 214 145 324 603 695 718 758 442 298 490 332 403 389 211 64 70
[10:46:15.073] INFO: number of X-ray hits detected: 148981 99835 148249 245956 261930 275868 262940 195112 178373 227145 210662 196159 196355 119689 52111 54843
[10:46:15.073] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:46:15.073] INFO: number of Vcal hits detected: 207776 207848 207628 207285 206765 207163 203221 207512 207680 207465 207557 207570 207483 207779 207934 207928
[10:46:15.073] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.6 99.6 99.8 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[10:46:15.073] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.4 99.6 97.7 99.8 99.8 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[10:46:15.073] INFO: X-ray hit rate [MHz/cm2]: 43.7 29.3 43.5 72.1 76.8 80.9 77.1 57.2 52.3 66.6 61.7 57.5 57.6 35.1 15.3 16.1
[10:46:15.073] INFO: PixTestHighRate::doXPixelAlive() done
[10:46:15.119] INFO: PixTest:: pg_setup set to default.
[10:46:15.131] INFO: enter test to run
[10:46:27.535] INFO: test: HighRate no parameter change
[10:46:27.535] INFO: running: highrate
[10:46:27.536] INFO: ----------------------------------------------------------------------
[10:46:27.536] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:46:27.536] INFO: ----------------------------------------------------------------------
[10:46:28.151] INFO: Expecting 208000 events.
[10:46:44.302] INFO: 208000 events read in total (15625ms).
[10:46:44.310] INFO: Test took 16766ms.
[10:46:44.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:45.167] INFO: number of dead pixels (per ROC): 0 0 0 0 9 0 80 0 0 0 2 0 2 0 0 0
[10:46:45.167] INFO: number of red-efficiency pixels: 427 247 659 1379 1530 1624 1748 1018 684 1108 692 916 895 424 108 116
[10:46:45.167] INFO: number of X-ray hits detected: 222050 150116 224190 368150 391874 415737 393603 293641 266980 341456 316994 295355 295470 179499 78959 83063
[10:46:45.167] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:46:45.167] INFO: number of Vcal hits detected: 207512 207736 207092 206137 205465 205568 201588 206619 207220 206601 207112 206855 206846 207522 207888 207882
[10:46:45.167] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.6 99.2 99.1 99.0 98.9 99.4 99.7 99.4 99.7 99.5 99.5 99.8 99.9 99.9
[10:46:45.167] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.1 98.8 98.8 96.9 99.3 99.6 99.3 99.6 99.4 99.4 99.8 99.9 99.9
[10:46:45.167] INFO: X-ray hit rate [MHz/cm2]: 65.1 44.0 65.7 107.9 114.9 121.9 115.4 86.1 78.3 100.1 92.9 86.6 86.6 52.6 23.1 24.3
[10:46:45.167] INFO: PixTestHighRate::doXPixelAlive() done
[10:46:45.215] INFO: PixTest:: pg_setup set to default.
[10:46:45.228] INFO: enter test to run
[10:46:53.175] INFO: test: exit no parameter change
[10:46:53.523] QUIET: Connection to board 33 closed.
[10:46:53.524] INFO: pXar: this is the end, my friend