[10:12:44.282] INFO: *** Welcome to pxar ***
[10:12:44.282] INFO: *** Today: 2016/09/02
[10:12:44.995] INFO: *** Version: v1.9.0-818-g96727
[10:12:44.995] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//dacParameters35_C15.dat
[10:12:45.059] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:12:45.059] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//defaultMaskFile.dat
[10:12:45.059] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C15.dat
[10:12:45.156] INFO: clk: 4
[10:12:45.156] INFO: ctr: 4
[10:12:45.156] INFO: sda: 19
[10:12:45.156] INFO: tin: 9
[10:12:45.156] INFO: level: 15
[10:12:45.156] INFO: triggerdelay: 0
[10:12:45.156] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:12:45.156] INFO: Log level: INFO
[10:12:45.172] QUIET: Connection to board DTB_WREKRL opened.
[10:12:45.175] 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:12:45.178] INFO: RPC call hashes of host and DTB match: 398089610
[10:12:46.705] INFO: DUT info:
[10:12:46.705] INFO: The DUT currently contains the following objects:
[10:12:46.705] INFO: 2 TBM Cores tbm08c (2 ON)
[10:12:46.705] INFO: TBM Core alpha (0): 7 registers set
[10:12:46.705] INFO: TBM Core beta (1): 7 registers set
[10:12:46.705] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:12:46.706] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:46.706] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:46.706] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:46.706] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:46.706] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:46.706] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:46.706] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:46.706] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:46.706] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:46.706] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:46.706] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:46.706] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:46.706] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:46.706] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:46.706] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:46.706] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.109] INFO: enter 'restricted' command line mode
[10:12:47.109] INFO: enter test to run
[10:12:51.387] INFO: test: PixelAlive no parameter change
[10:12:51.387] INFO: running: pixelalive
[10:12:51.400] INFO: ----------------------------------------------------------------------
[10:12:51.400] 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:12:51.400] INFO: ----------------------------------------------------------------------
[10:12:51.729] INFO: Expecting 41600 events.
[10:12:56.088] INFO: 41600 events read in total (3641ms).
[10:12:56.255] INFO: Test took 4853ms.
[10:12:56.265] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:56.528] INFO: PixTestAlive::aliveTest() done
[10:12:56.528] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[10:12:56.556] INFO: enter test to run
[10:13:24.659] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:13:24.659] INFO: running: highrate
[10:13:24.659] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:13:24.901] INFO: ----------------------------------------------------------------------
[10:13:24.901] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:13:24.901] INFO: ----------------------------------------------------------------------
[10:13:24.901] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:13:24.901] INFO: edge/corner pixel THR is adjusted
[10:13:24.901] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:13:25.859] INFO: Collecting data for 5 seconds...
[10:13:30.877] INFO: Done with hot pixel readout
[10:13:42.542] INFO: PixTest:: pg_setup set to default.
[10:13:42.543] INFO: 16 hot pixels found in step 0
[10:13:43.557] INFO: Collecting data for 5 seconds...
[10:13:48.576] INFO: Done with hot pixel readout
[10:14:00.310] INFO: PixTest:: pg_setup set to default.
[10:14:00.311] INFO: 13 hot pixels found in step 1
[10:14:01.299] INFO: Collecting data for 5 seconds...
[10:14:06.317] INFO: Done with hot pixel readout
[10:14:17.956] INFO: PixTest:: pg_setup set to default.
[10:14:17.957] INFO: 18 hot pixels found in step 2
[10:14:18.945] INFO: Collecting data for 5 seconds...
[10:14:23.967] INFO: Done with hot pixel readout
[10:14:35.870] INFO: PixTest:: pg_setup set to default.
[10:14:35.871] INFO: 11 hot pixels found in step 3
[10:14:36.859] INFO: Collecting data for 5 seconds...
[10:14:42.032] INFO: Done with hot pixel readout
[10:14:54.360] INFO: PixTest:: pg_setup set to default.

[10:14:54.361] INFO: 18 hot pixels found in step 4
[10:14:55.352] INFO: Collecting data for 5 seconds...
[10:15:00.372] INFO: Done with hot pixel readout
[10:15:12.302] INFO: PixTest:: pg_setup set to default.
[10:15:12.303] INFO: 9 hot pixels found in step 5
[10:15:13.290] INFO: Collecting data for 5 seconds...
[10:15:18.309] INFO: Done with hot pixel readout
[10:15:30.215] INFO: PixTest:: pg_setup set to default.
[10:15:30.216] INFO: 11 hot pixels found in step 6
[10:15:31.204] INFO: Collecting data for 5 seconds...
[10:15:36.222] INFO: Done with hot pixel readout
[10:15:48.274] INFO: PixTest:: pg_setup set to default.
[10:15:48.275] INFO: 12 hot pixels found in step 7
[10:15:49.263] INFO: Collecting data for 5 seconds...
[10:15:54.283] INFO: Done with hot pixel readout
[10:16:06.306] INFO: PixTest:: pg_setup set to default.
[10:16:06.307] INFO: 5 hot pixels found in step 8
[10:16:07.295] INFO: Collecting data for 5 seconds...
[10:16:12.317] INFO: Done with hot pixel readout
[10:16:24.405] INFO: PixTest:: pg_setup set to default.
[10:16:24.406] INFO: 6 hot pixels found in step 9
[10:16:25.394] INFO: Collecting data for 5 seconds...
[10:16:30.411] INFO: Done with hot pixel readout
[10:16:42.162] INFO: PixTest:: pg_setup set to default.
[10:16:42.163] INFO: 3 hot pixels found in step 10
[10:16:43.151] INFO: Collecting data for 5 seconds...
[10:16:48.167] INFO: Done with hot pixel readout
[10:16:59.840] INFO: PixTest:: pg_setup set to default.
[10:16:59.841] INFO: 5 hot pixels found in step 11
[10:17:00.828] INFO: Collecting data for 5 seconds...
[10:17:05.844] INFO: Done with hot pixel readout
[10:17:17.541] INFO: PixTest:: pg_setup set to default.
[10:17:17.542] INFO: 3 hot pixels found in step 12
[10:17:18.530] INFO: Collecting data for 5 seconds...
[10:17:23.546] INFO: Done with hot pixel readout
[10:17:35.256] INFO: PixTest:: pg_setup set to default.
[10:17:35.257] INFO: 5 hot pixels found in step 13
[10:17:36.246] INFO: Collecting data for 5 seconds...
[10:17:41.262] INFO: Done with hot pixel readout
[10:17:52.966] INFO: PixTest:: pg_setup set to default.
[10:17:52.967] INFO: 4 hot pixels found in step 14
[10:17:52.000] INFO: 4 hot pixels could not be trimmed and have been masked.
[10:17:52.003] INFO: PixTest::trimHotPixels() done
[10:17:52.006] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C0.dat
[10:17:53.011] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C1.dat
[10:17:53.017] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C2.dat
[10:17:53.023] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C3.dat
[10:17:53.029] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C4.dat
[10:17:53.034] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C5.dat
[10:17:53.040] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C6.dat
[10:17:53.045] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C7.dat
[10:17:53.051] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C8.dat
[10:17:53.056] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C9.dat
[10:17:53.062] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C10.dat
[10:17:53.067] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C11.dat
[10:17:53.073] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C12.dat
[10:17:53.078] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C13.dat
[10:17:53.084] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C14.dat
[10:17:53.089] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C15.dat
[10:17:53.095] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//defaultMaskFile.dat
[10:17:53.105] INFO: enter test to run
[10:18:57.265] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:18:57.265] INFO: running: highrate
[10:18:57.269] INFO: ----------------------------------------------------------------------
[10:18:57.269] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:18:57.269] INFO: ----------------------------------------------------------------------
[10:18:57.269] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:18:57.269] INFO: edge/corner pixel THR is adjusted
[10:18:57.269] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:18:58.232] INFO: Collecting data for 1 seconds...
[10:18:59.236] INFO: Done with hot pixel readout
[10:19:03.214] INFO: PixTest:: pg_setup set to default.
[10:19:03.215] INFO: 0 hot pixels found in step 0
[10:19:03.220] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:19:03.309] INFO: PixTest::trimHotPixels() done
[10:19:03.310] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C0.dat
[10:19:03.318] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C1.dat
[10:19:03.323] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C2.dat
[10:19:03.328] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C3.dat
[10:19:03.334] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C4.dat
[10:19:03.339] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C5.dat
[10:19:03.345] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C6.dat
[10:19:03.350] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C7.dat
[10:19:03.355] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C8.dat
[10:19:03.361] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C9.dat
[10:19:03.366] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C10.dat
[10:19:03.372] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C11.dat
[10:19:03.377] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C12.dat
[10:19:03.382] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C13.dat
[10:19:03.388] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C14.dat
[10:19:03.393] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C15.dat
[10:19:03.399] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-25_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//defaultMaskFile.dat
[10:19:03.412] INFO: enter test to run
[10:19:18.184] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:19:18.184] INFO: running: xray
[10:19:18.186] INFO: ----------------------------------------------------------------------
[10:19:18.186] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:19:18.186] INFO: ----------------------------------------------------------------------
[10:19:19.148] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:19:30.729] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:20:00.294] INFO: Resuming triggers.
[10:20:11.868] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:20:41.370] INFO: Resuming triggers.
[10:20:52.951] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:21:22.455] INFO: Resuming triggers.
[10:21:34.029] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:22:03.376] INFO: Resuming triggers.
[10:22:14.952] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[10:22:44.408] INFO: Resuming triggers.
[10:22:55.982] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:23:25.457] INFO: Resuming triggers.
[10:23:37.030] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:24:06.440] INFO: Resuming triggers.
[10:24:18.011] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:24:47.784] INFO: Resuming triggers.
[10:24:55.510] INFO: data taking finished, elapsed time: 100 seconds.
[10:25:15.255] INFO: PixTest:: pg_setup set to default.
[10:25:15.258] INFO: PixTestXray::doPhRun() done
[10:25:15.427] INFO: enter test to run
[10:25:43.342] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:25:43.342] INFO: running: xray
[10:25:43.343] INFO: ----------------------------------------------------------------------
[10:25:43.343] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:25:43.343] INFO: ----------------------------------------------------------------------
[10:25:44.313] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:25:51.037] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:26:21.739] INFO: Resuming triggers.
[10:26:28.462] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:26:59.606] INFO: Resuming triggers.
[10:27:06.329] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[10:27:37.878] INFO: Resuming triggers.
[10:27:44.598] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[10:28:15.787] INFO: Resuming triggers.
[10:28:22.507] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:28:53.965] INFO: Resuming triggers.
[10:29:00.682] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[10:29:30.823] INFO: Resuming triggers.
[10:29:37.545] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:30:07.675] INFO: Resuming triggers.
[10:30:14.395] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[10:30:44.543] INFO: Resuming triggers.
[10:30:51.263] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[10:31:21.410] INFO: Resuming triggers.
[10:31:28.135] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[10:31:58.270] INFO: Resuming triggers.
[10:32:04.992] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[10:32:35.164] INFO: Resuming triggers.
[10:32:41.885] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:33:11.003] INFO: Resuming triggers.
[10:33:18.730] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[10:33:48.913] INFO: Resuming triggers.
[10:33:55.632] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[10:34:25.888] INFO: Resuming triggers.
[10:34:32.133] INFO: data taking finished, elapsed time: 100 seconds.
[10:35:00.312] INFO: PixTest:: pg_setup set to default.
[10:35:00.315] INFO: PixTestXray::doPhRun() done
[10:35:00.484] INFO: enter test to run
[10:37:01.233] INFO: test: HighRate no parameter change
[10:37:01.233] INFO: running: highrate
[10:37:01.245] INFO: ----------------------------------------------------------------------
[10:37:01.245] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:37:01.245] INFO: ----------------------------------------------------------------------
[10:37:01.391] INFO: Expecting 768 events.
[10:37:02.533] INFO: 768 events read in total (421ms).
[10:37:02.533] INFO: Test took 1276ms.
[10:37:03.335] INFO: Expecting 41600 events.
[10:37:06.466] INFO: 41600 events read in total (2604ms).
[10:37:06.467] INFO: Test took 3908ms.
[10:37:06.502] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:07.223] INFO: Expecting 41600 events.
[10:37:10.385] INFO: 41600 events read in total (2636ms).
[10:37:10.386] INFO: Test took 3867ms.
[10:37:10.428] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:11.131] INFO: Expecting 41600 events.
[10:37:14.404] INFO: 41600 events read in total (2746ms).
[10:37:14.404] INFO: Test took 3950ms.
[10:37:14.440] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:15.162] INFO: Expecting 41600 events.
[10:37:18.356] INFO: 41600 events read in total (2667ms).
[10:37:18.357] INFO: Test took 3899ms.
[10:37:18.392] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:19.107] INFO: Expecting 41600 events.
[10:37:22.367] INFO: 41600 events read in total (2734ms).
[10:37:22.368] INFO: Test took 3958ms.
[10:37:22.403] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:23.122] INFO: Expecting 41600 events.
[10:37:26.353] INFO: 41600 events read in total (2704ms).
[10:37:26.354] INFO: Test took 3934ms.
[10:37:26.388] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:27.109] INFO: Expecting 41600 events.
[10:37:30.299] INFO: 41600 events read in total (2663ms).
[10:37:30.300] INFO: Test took 3894ms.
[10:37:30.335] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:31.054] INFO: Expecting 41600 events.
[10:37:34.381] INFO: 41600 events read in total (2800ms).
[10:37:34.382] INFO: Test took 4028ms.
[10:37:34.416] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:35.136] INFO: Expecting 41600 events.
[10:37:38.386] INFO: 41600 events read in total (2723ms).
[10:37:38.387] INFO: Test took 3953ms.
[10:37:38.421] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:39.140] INFO: Expecting 41600 events.
[10:37:42.383] INFO: 41600 events read in total (2716ms).
[10:37:42.384] INFO: Test took 3944ms.
[10:37:42.418] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:43.138] INFO: Expecting 41600 events.
[10:37:46.378] INFO: 41600 events read in total (2713ms).
[10:37:46.379] INFO: Test took 3941ms.
[10:37:46.414] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:47.132] INFO: Expecting 41600 events.
[10:37:50.405] INFO: 41600 events read in total (2746ms).
[10:37:50.406] INFO: Test took 3973ms.
[10:37:50.442] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:51.159] INFO: Expecting 41600 events.
[10:37:54.368] INFO: 41600 events read in total (2682ms).
[10:37:54.369] INFO: Test took 3910ms.
[10:37:54.406] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:55.120] INFO: Expecting 41600 events.
[10:37:58.386] INFO: 41600 events read in total (2739ms).
[10:37:58.386] INFO: Test took 3961ms.
[10:37:58.422] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:59.143] INFO: Expecting 41600 events.
[10:38:02.447] INFO: 41600 events read in total (2778ms).
[10:38:02.448] INFO: Test took 4007ms.
[10:38:02.483] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:03.202] INFO: Expecting 41600 events.
[10:38:06.443] INFO: 41600 events read in total (2714ms).
[10:38:06.444] INFO: Test took 3943ms.
[10:38:06.479] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:07.198] INFO: Expecting 41600 events.
[10:38:10.441] INFO: 41600 events read in total (2716ms).
[10:38:10.442] INFO: Test took 3943ms.
[10:38:10.477] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:11.198] INFO: Expecting 41600 events.
[10:38:14.480] INFO: 41600 events read in total (2755ms).
[10:38:14.481] INFO: Test took 3986ms.
[10:38:14.517] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:15.236] INFO: Expecting 41600 events.
[10:38:18.400] INFO: 41600 events read in total (2637ms).
[10:38:18.402] INFO: Test took 3866ms.
[10:38:18.437] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:19.157] INFO: Expecting 41600 events.
[10:38:22.138] INFO: 41600 events read in total (2454ms).
[10:38:22.139] INFO: Test took 3684ms.
[10:38:22.173] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:22.540] INFO: enter test to run
[10:38:39.153] INFO: test: HighRate no parameter change
[10:38:39.153] INFO: running: highrate
[10:38:39.154] INFO: ----------------------------------------------------------------------
[10:38:39.154] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:38:39.154] INFO: ----------------------------------------------------------------------
[10:38:39.769] INFO: Expecting 208000 events.
[10:38:51.536] INFO: 208000 events read in total (11240ms).
[10:38:51.539] INFO: Test took 12376ms.
[10:38:51.686] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:38:51.941] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[10:38:51.941] INFO: number of red-efficiency pixels: 112 41 80 160 168 141 170 86 72 153 117 105 102 54 21 23
[10:38:51.941] INFO: number of X-ray hits detected: 67866 43087 69269 108978 112221 114192 114548 79571 73368 98333 98305 83928 86938 52972 23490 26521
[10:38:51.941] 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:38:51.941] INFO: number of Vcal hits detected: 207883 207958 207919 207836 207780 207858 207824 207910 207926 207846 207881 207891 207896 207946 207978 207977
[10:38:51.941] INFO: Vcal hit fiducial efficiency (%): 99.9 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
[10:38:51.941] INFO: Vcal hit overall efficiency (%): 99.9 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[10:38:51.941] INFO: X-ray hit rate [MHz/cm2]: 19.9 12.6 20.3 31.9 32.9 33.5 33.6 23.3 21.5 28.8 28.8 24.6 25.5 15.5 6.9 7.8
[10:38:51.941] INFO: PixTestHighRate::doXPixelAlive() done
[10:38:51.987] INFO: PixTest:: pg_setup set to default.
[10:38:51.001] INFO: enter test to run
[10:39:03.537] INFO: test: HighRate no parameter change
[10:39:03.537] INFO: running: highrate
[10:39:03.538] INFO: ----------------------------------------------------------------------
[10:39:03.538] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:39:03.538] INFO: ----------------------------------------------------------------------
[10:39:04.162] INFO: Expecting 208000 events.
[10:39:17.533] INFO: 208000 events read in total (12845ms).
[10:39:17.538] INFO: Test took 13991ms.
[10:39:17.845] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:39:18.150] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[10:39:18.150] INFO: number of red-efficiency pixels: 240 82 285 581 437 451 556 258 202 422 421 250 343 206 64 71
[10:39:18.150] INFO: number of X-ray hits detected: 139105 87481 141935 223798 230035 233646 234558 162841 150928 201211 201577 171488 177813 108117 49088 54085
[10:39:18.150] 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:39:18.151] INFO: number of Vcal hits detected: 207742 207916 207685 207365 207476 207519 207393 207732 207789 207547 207544 207738 207627 207781 207934 207928
[10:39:18.151] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.7 99.8 99.8 99.7 99.9 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[10:39:18.151] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.8 99.7 99.7 99.8 99.7 99.9 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[10:39:18.151] INFO: X-ray hit rate [MHz/cm2]: 40.8 25.6 41.6 65.6 67.4 68.5 68.8 47.7 44.2 59.0 59.1 50.3 52.1 31.7 14.4 15.9
[10:39:18.151] INFO: PixTestHighRate::doXPixelAlive() done
[10:39:18.198] INFO: PixTest:: pg_setup set to default.
[10:39:18.217] INFO: enter test to run
[10:39:43.504] INFO: test: HighRate no parameter change
[10:39:43.504] INFO: running: highrate
[10:39:43.505] INFO: ----------------------------------------------------------------------
[10:39:43.505] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:39:43.505] INFO: ----------------------------------------------------------------------
[10:39:44.126] INFO: Expecting 208000 events.
[10:40:00.130] INFO: 208000 events read in total (15478ms).
[10:40:00.138] INFO: Test took 16624ms.
[10:40:00.604] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:00.957] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[10:40:00.957] INFO: number of red-efficiency pixels: 512 228 598 1195 1042 1024 1281 568 405 962 969 548 765 446 104 103
[10:40:00.957] INFO: number of X-ray hits detected: 207139 130712 213252 334872 344649 348028 349900 244591 225992 301938 301649 258533 266340 162586 73245 81588
[10:40:00.957] 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:40:00.957] INFO: number of Vcal hits detected: 207382 207758 207220 206465 206692 206742 206344 207329 207557 206828 206807 207393 207125 207476 207890 207895
[10:40:00.957] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.7 99.3 99.5 99.5 99.3 99.7 99.8 99.5 99.5 99.7 99.6 99.8 99.9 100.0
[10:40:00.957] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.6 99.3 99.4 99.4 99.2 99.7 99.8 99.4 99.4 99.7 99.6 99.7 99.9 99.9
[10:40:00.957] INFO: X-ray hit rate [MHz/cm2]: 60.7 38.3 62.5 98.2 101.0 102.0 102.6 71.7 66.2 88.5 88.4 75.8 78.1 47.7 21.5 23.9
[10:40:00.957] INFO: PixTestHighRate::doXPixelAlive() done
[10:40:00.002] INFO: PixTest:: pg_setup set to default.
[10:40:01.016] INFO: enter test to run
[10:40:04.272] INFO: test: exit no parameter change
[10:40:04.694] QUIET: Connection to board 33 closed.
[10:40:04.707] INFO: pXar: this is the end, my friend