[10:06:08.263] INFO: *** Welcome to pxar ***
[10:06:08.263] INFO: *** Today: 2016/10/03
[10:06:08.280] INFO: *** Version: v1.9.0-818-g96727
[10:06:08.280] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//dacParameters35_C15.dat
[10:06:08.281] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:06:08.281] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//defaultMaskFile.dat
[10:06:08.281] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C15.dat
[10:06:08.355] INFO: clk: 4
[10:06:08.355] INFO: ctr: 4
[10:06:08.355] INFO: sda: 19
[10:06:08.355] INFO: tin: 9
[10:06:08.355] INFO: level: 15
[10:06:08.355] INFO: triggerdelay: 0
[10:06:08.355] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:06:08.355] INFO: Log level: INFO
[10:06:08.373] QUIET: Connection to board DTB_WREKRL opened.
[10:06:08.376] 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:06:08.379] INFO: RPC call hashes of host and DTB match: 398089610
[10:06:09.913] INFO: DUT info:
[10:06:09.913] INFO: The DUT currently contains the following objects:
[10:06:09.913] INFO: 2 TBM Cores tbm08c (2 ON)
[10:06:09.913] INFO: TBM Core alpha (0): 7 registers set
[10:06:09.913] INFO: TBM Core beta (1): 7 registers set
[10:06:09.913] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:06:09.914] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:09.914] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:09.914] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:09.914] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:09.914] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:09.914] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:09.914] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:09.914] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:09.914] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:09.914] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:09.914] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:09.914] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:09.914] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:09.914] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:09.914] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:09.914] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:10.360] INFO: enter 'restricted' command line mode
[10:06:10.360] INFO: enter test to run
[10:06:17.521] INFO: test: PixelAlive no parameter change
[10:06:17.521] INFO: running: pixelalive
[10:06:17.572] INFO: ----------------------------------------------------------------------
[10:06:17.572] 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:06:17.572] INFO: ----------------------------------------------------------------------
[10:06:17.894] INFO: Expecting 41600 events.
[10:06:22.227] INFO: 41600 events read in total (3614ms).
[10:06:22.394] INFO: Test took 4820ms.
[10:06:22.407] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:06:22.707] INFO: PixTestAlive::aliveTest() done
[10:06:22.707] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:06:22.878] INFO: enter test to run
[10:06:42.681] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:06:42.681] INFO: running: highrate
[10:06:42.734] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:06:42.999] INFO: ----------------------------------------------------------------------
[10:06:42.000] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:06:42.000] INFO: ----------------------------------------------------------------------
[10:06:42.000] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:06:42.000] INFO: edge/corner pixel THR is adjusted
[10:06:42.000] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:06:43.960] INFO: Collecting data for 5 seconds...
[10:06:48.977] INFO: Done with hot pixel readout
[10:07:00.784] INFO: PixTest:: pg_setup set to default.
[10:07:00.785] INFO: 10 hot pixels found in step 0
[10:07:01.778] INFO: Collecting data for 5 seconds...
[10:07:06.795] INFO: Done with hot pixel readout
[10:07:18.611] INFO: PixTest:: pg_setup set to default.
[10:07:18.612] INFO: 8 hot pixels found in step 1
[10:07:19.608] INFO: Collecting data for 5 seconds...
[10:07:24.625] INFO: Done with hot pixel readout
[10:07:36.437] INFO: PixTest:: pg_setup set to default.
[10:07:36.437] INFO: 7 hot pixels found in step 2
[10:07:37.432] INFO: Collecting data for 5 seconds...
[10:07:42.448] INFO: Done with hot pixel readout
[10:07:54.339] INFO: PixTest:: pg_setup set to default.


[10:07:54.340] INFO: 9 hot pixels found in step 3
[10:07:55.334] INFO: Collecting data for 5 seconds...
[10:08:00.352] INFO: Done with hot pixel readout
[10:08:12.112] INFO: PixTest:: pg_setup set to default.
[10:08:12.113] INFO: 14 hot pixels found in step 4
[10:08:13.108] INFO: Collecting data for 5 seconds...
[10:08:18.126] INFO: Done with hot pixel readout
[10:08:29.985] INFO: PixTest:: pg_setup set to default.
[10:08:29.986] INFO: 13 hot pixels found in step 5
[10:08:30.982] INFO: Collecting data for 5 seconds...
[10:08:35.001] INFO: Done with hot pixel readout
[10:08:47.795] INFO: PixTest:: pg_setup set to default.

[10:08:47.796] INFO: 7 hot pixels found in step 6
[10:08:48.791] INFO: Collecting data for 5 seconds...
[10:08:53.809] INFO: Done with hot pixel readout
[10:09:05.613] INFO: PixTest:: pg_setup set to default.

[10:09:05.614] INFO: 7 hot pixels found in step 7
[10:09:06.608] INFO: Collecting data for 5 seconds...
[10:09:11.627] INFO: Done with hot pixel readout
[10:09:23.590] INFO: PixTest:: pg_setup set to default.
[10:09:23.591] INFO: 8 hot pixels found in step 8
[10:09:24.586] INFO: Collecting data for 5 seconds...
[10:09:29.606] INFO: Done with hot pixel readout
[10:09:41.445] INFO: PixTest:: pg_setup set to default.

[10:09:41.446] INFO: 7 hot pixels found in step 9
[10:09:42.441] INFO: Collecting data for 5 seconds...
[10:09:47.463] INFO: Done with hot pixel readout
[10:09:59.244] INFO: PixTest:: pg_setup set to default.
[10:09:59.245] INFO: 8 hot pixels found in step 10
[10:10:00.240] INFO: Collecting data for 5 seconds...
[10:10:05.260] INFO: Done with hot pixel readout
[10:10:17.061] INFO: PixTest:: pg_setup set to default.
[10:10:17.061] INFO: 9 hot pixels found in step 11
[10:10:18.056] INFO: Collecting data for 5 seconds...
[10:10:23.075] INFO: Done with hot pixel readout
[10:10:34.740] INFO: PixTest:: pg_setup set to default.
[10:10:34.740] INFO: 7 hot pixels found in step 12
[10:10:35.735] INFO: Collecting data for 5 seconds...
[10:10:40.754] INFO: Done with hot pixel readout
[10:10:52.627] INFO: PixTest:: pg_setup set to default.
[10:10:52.628] INFO: 10 hot pixels found in step 13
[10:10:53.623] INFO: Collecting data for 5 seconds...
[10:10:58.643] INFO: Done with hot pixel readout
[10:11:10.346] INFO: PixTest:: pg_setup set to default.
[10:11:10.347] INFO: 10 hot pixels found in step 14
[10:11:10.386] INFO: 10 hot pixels could not be trimmed and have been masked.
[10:11:10.390] INFO: PixTest::trimHotPixels() done
[10:11:10.619] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C0.dat
[10:11:10.629] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C1.dat
[10:11:10.637] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C2.dat
[10:11:10.642] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C3.dat
[10:11:10.648] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C4.dat
[10:11:10.653] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C5.dat
[10:11:10.659] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C6.dat
[10:11:10.664] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C7.dat
[10:11:10.670] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C8.dat
[10:11:10.675] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C9.dat
[10:11:10.681] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C10.dat
[10:11:10.686] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C11.dat
[10:11:10.691] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C12.dat
[10:11:10.697] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C13.dat
[10:11:10.702] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C14.dat
[10:11:10.708] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C15.dat
[10:11:10.713] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//defaultMaskFile.dat
[10:11:10.724] INFO: enter test to run
[10:11:31.160] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:11:31.160] INFO: running: highrate
[10:11:31.164] INFO: ----------------------------------------------------------------------
[10:11:31.164] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:11:31.164] INFO: ----------------------------------------------------------------------
[10:11:31.164] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:11:31.164] INFO: edge/corner pixel THR is adjusted
[10:11:31.164] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:11:32.122] INFO: Collecting data for 1 seconds...
[10:11:33.127] INFO: Done with hot pixel readout
[10:11:37.444] INFO: PixTest:: pg_setup set to default.
[10:11:37.445] INFO: 0 hot pixels found in step 0
[10:11:37.450] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:11:37.528] INFO: PixTest::trimHotPixels() done
[10:11:37.528] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C0.dat
[10:11:37.559] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C1.dat
[10:11:37.565] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C2.dat
[10:11:37.571] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C3.dat
[10:11:37.576] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C4.dat
[10:11:37.582] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C5.dat
[10:11:37.587] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C6.dat
[10:11:37.593] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C7.dat
[10:11:37.598] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C8.dat
[10:11:37.604] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C9.dat
[10:11:37.609] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C10.dat
[10:11:37.614] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C11.dat
[10:11:37.620] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C12.dat
[10:11:37.625] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C13.dat
[10:11:37.631] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C14.dat
[10:11:37.636] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//trimParameters35_C15.dat
[10:11:37.641] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-1-49_FPIXTest-17C-FNAL-160926-1425-150V_2016-09-26_15h05m_1474920344/000_FPIXTest_p17//defaultMaskFile.dat
[10:11:37.651] INFO: enter test to run
[10:11:58.039] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:11:58.039] INFO: running: xray
[10:11:58.041] INFO: ----------------------------------------------------------------------
[10:11:58.041] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:11:58.041] INFO: ----------------------------------------------------------------------
[10:11:59.015] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:12:10.334] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:12:40.879] INFO: Resuming triggers.
[10:12:52.204] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[10:13:22.645] INFO: Resuming triggers.
[10:13:33.965] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:14:03.503] INFO: Resuming triggers.
[10:14:14.824] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:14:44.302] INFO: Resuming triggers.
[10:14:55.625] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[10:15:25.270] INFO: Resuming triggers.
[10:15:36.590] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[10:16:06.112] INFO: Resuming triggers.
[10:16:17.443] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[10:16:47.068] INFO: Resuming triggers.
[10:16:58.396] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[10:17:27.903] INFO: Resuming triggers.
[10:17:37.671] INFO: data taking finished, elapsed time: 100 seconds.
[10:18:03.289] INFO: PixTest:: pg_setup set to default.
[10:18:03.293] INFO: PixTestXray::doPhRun() done
[10:18:03.478] INFO: enter test to run
[10:18:22.901] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:18:22.901] INFO: running: xray
[10:18:22.902] INFO: ----------------------------------------------------------------------
[10:18:22.902] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:18:22.902] INFO: ----------------------------------------------------------------------
[10:18:23.873] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:18:30.384] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:19:00.727] INFO: Resuming triggers.
[10:19:07.238] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[10:19:37.484] INFO: Resuming triggers.
[10:19:43.996] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[10:20:14.251] INFO: Resuming triggers.
[10:20:20.761] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[10:20:51.055] INFO: Resuming triggers.
[10:20:57.562] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[10:21:28.626] INFO: Resuming triggers.
[10:21:35.134] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[10:22:06.122] INFO: Resuming triggers.
[10:22:12.632] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:22:43.778] INFO: Resuming triggers.
[10:22:50.286] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[10:23:21.170] INFO: Resuming triggers.
[10:23:27.678] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[10:23:59.310] INFO: Resuming triggers.
[10:24:05.818] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[10:24:37.352] INFO: Resuming triggers.
[10:24:43.858] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[10:25:15.504] INFO: Resuming triggers.
[10:25:22.010] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[10:25:53.383] INFO: Resuming triggers.
[10:25:59.891] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[10:26:30.128] INFO: Resuming triggers.
[10:26:36.636] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[10:27:06.962] INFO: Resuming triggers.
[10:27:13.470] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[10:27:43.829] INFO: Resuming triggers.
[10:27:46.561] INFO: data taking finished, elapsed time: 100 seconds.
[10:27:59.653] INFO: PixTest:: pg_setup set to default.
[10:27:59.656] INFO: PixTestXray::doPhRun() done
[10:27:59.806] INFO: enter test to run
[10:28:19.560] INFO: test: HighRate no parameter change
[10:28:19.561] INFO: running: highrate
[10:28:19.573] INFO: ----------------------------------------------------------------------
[10:28:19.573] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:28:19.573] INFO: ----------------------------------------------------------------------
[10:28:19.728] INFO: Expecting 768 events.
[10:28:20.862] INFO: 768 events read in total (418ms).
[10:28:20.863] INFO: Test took 1269ms.
[10:28:21.666] INFO: Expecting 41600 events.
[10:28:24.752] INFO: 41600 events read in total (2559ms).
[10:28:24.753] INFO: Test took 3865ms.
[10:28:24.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:25.510] INFO: Expecting 41600 events.
[10:28:28.716] INFO: 41600 events read in total (2679ms).
[10:28:28.718] INFO: Test took 3914ms.
[10:28:28.753] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:29.472] INFO: Expecting 41600 events.
[10:28:32.713] INFO: 41600 events read in total (2714ms).
[10:28:32.714] INFO: Test took 3943ms.
[10:28:32.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:33.471] INFO: Expecting 41600 events.
[10:28:36.699] INFO: 41600 events read in total (2701ms).
[10:28:36.700] INFO: Test took 3933ms.
[10:28:36.734] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:37.460] INFO: Expecting 41600 events.
[10:28:40.691] INFO: 41600 events read in total (2704ms).
[10:28:40.692] INFO: Test took 3940ms.
[10:28:40.725] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:41.447] INFO: Expecting 41600 events.
[10:28:44.690] INFO: 41600 events read in total (2716ms).
[10:28:44.691] INFO: Test took 3948ms.
[10:28:44.726] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:45.450] INFO: Expecting 41600 events.
[10:28:48.697] INFO: 41600 events read in total (2720ms).
[10:28:48.698] INFO: Test took 3954ms.
[10:28:48.732] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:49.457] INFO: Expecting 41600 events.
[10:28:52.693] INFO: 41600 events read in total (2709ms).
[10:28:52.694] INFO: Test took 3943ms.
[10:28:52.728] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:53.453] INFO: Expecting 41600 events.
[10:28:56.702] INFO: 41600 events read in total (2722ms).
[10:28:56.703] INFO: Test took 3956ms.
[10:28:56.737] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:57.460] INFO: Expecting 41600 events.
[10:29:00.696] INFO: 41600 events read in total (2709ms).
[10:29:00.697] INFO: Test took 3941ms.
[10:29:00.732] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:01.455] INFO: Expecting 41600 events.
[10:29:04.693] INFO: 41600 events read in total (2711ms).
[10:29:04.694] INFO: Test took 3944ms.
[10:29:04.729] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:05.452] INFO: Expecting 41600 events.
[10:29:08.691] INFO: 41600 events read in total (2712ms).
[10:29:08.691] INFO: Test took 3943ms.
[10:29:08.726] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:09.450] INFO: Expecting 41600 events.
[10:29:12.718] INFO: 41600 events read in total (2742ms).
[10:29:12.720] INFO: Test took 3975ms.
[10:29:12.763] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:13.473] INFO: Expecting 41600 events.
[10:29:16.707] INFO: 41600 events read in total (2707ms).
[10:29:16.708] INFO: Test took 3926ms.
[10:29:16.744] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:17.469] INFO: Expecting 41600 events.
[10:29:20.703] INFO: 41600 events read in total (2707ms).
[10:29:20.704] INFO: Test took 3943ms.
[10:29:20.738] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:21.464] INFO: Expecting 41600 events.
[10:29:24.718] INFO: 41600 events read in total (2727ms).
[10:29:24.719] INFO: Test took 3961ms.
[10:29:24.753] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:25.474] INFO: Expecting 41600 events.
[10:29:28.726] INFO: 41600 events read in total (2725ms).
[10:29:28.727] INFO: Test took 3954ms.
[10:29:28.762] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:29.483] INFO: Expecting 41600 events.
[10:29:32.725] INFO: 41600 events read in total (2716ms).
[10:29:32.726] INFO: Test took 3945ms.
[10:29:32.761] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:33.483] INFO: Expecting 41600 events.
[10:29:36.673] INFO: 41600 events read in total (2663ms).
[10:29:36.674] INFO: Test took 3894ms.
[10:29:36.708] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:37.432] INFO: Expecting 41600 events.
[10:29:40.463] INFO: 41600 events read in total (2504ms).
[10:29:40.464] INFO: Test took 3738ms.
[10:29:40.498] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:40.861] INFO: enter test to run
[10:30:01.432] INFO: test: HighRate no parameter change
[10:30:01.432] INFO: running: highrate
[10:30:01.433] INFO: ----------------------------------------------------------------------
[10:30:01.433] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:30:01.433] INFO: ----------------------------------------------------------------------
[10:30:02.052] INFO: Expecting 208000 events.
[10:30:13.892] INFO: 208000 events read in total (11313ms).
[10:30:13.895] INFO: Test took 12451ms.
[10:30:14.040] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:14.294] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:30:14.294] INFO: number of red-efficiency pixels: 68 46 88 120 133 150 143 101 91 153 138 131 88 80 23 24
[10:30:14.294] INFO: number of X-ray hits detected: 66391 43028 62709 98861 102788 112801 107674 76939 85251 112568 110946 93429 92422 57146 22231 23798
[10:30:14.294] 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:30:14.294] INFO: number of Vcal hits detected: 207929 207954 207906 207877 207867 207843 207853 207897 207908 207844 207850 207869 207911 207917 207977 207976
[10:30:14.294] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[10:30:14.294] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[10:30:14.294] INFO: X-ray hit rate [MHz/cm2]: 19.5 12.6 18.4 29.0 30.1 33.1 31.6 22.6 25.0 33.0 32.5 27.4 27.1 16.7 6.5 7.0
[10:30:14.294] INFO: PixTestHighRate::doXPixelAlive() done
[10:30:14.347] INFO: PixTest:: pg_setup set to default.
[10:30:14.364] INFO: enter test to run
[10:30:31.480] INFO: test: HighRate no parameter change
[10:30:31.480] INFO: running: highrate
[10:30:31.481] INFO: ----------------------------------------------------------------------
[10:30:31.481] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:30:31.481] INFO: ----------------------------------------------------------------------
[10:30:32.103] INFO: Expecting 208000 events.
[10:30:45.804] INFO: 208000 events read in total (13174ms).
[10:30:45.810] INFO: Test took 14320ms.
[10:30:46.133] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:46.439] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:30:46.439] INFO: number of red-efficiency pixels: 208 141 289 464 385 598 541 299 328 509 450 444 320 280 52 56
[10:30:46.440] INFO: number of X-ray hits detected: 142452 92129 133011 210758 219035 239755 228951 163398 181981 240424 236138 199988 196317 122020 47962 50618
[10:30:46.440] 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:30:46.440] INFO: number of Vcal hits detected: 207780 207852 207675 207493 207595 207330 207413 207680 207648 207444 207480 207498 207657 207683 207948 207943
[10:30:46.440] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.7 99.9 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[10:30:46.440] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.7 99.7 99.8 99.8 99.7 99.8 99.8 99.8 99.8 100.0 100.0
[10:30:46.440] INFO: X-ray hit rate [MHz/cm2]: 41.8 27.0 39.0 61.8 64.2 70.3 67.1 47.9 53.3 70.5 69.2 58.6 57.5 35.8 14.1 14.8
[10:30:46.440] INFO: PixTestHighRate::doXPixelAlive() done
[10:30:46.494] INFO: PixTest:: pg_setup set to default.
[10:30:46.507] INFO: enter test to run
[10:31:09.776] INFO: test: HighRate no parameter change
[10:31:09.776] INFO: running: highrate
[10:31:09.777] INFO: ----------------------------------------------------------------------
[10:31:09.777] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:31:09.777] INFO: ----------------------------------------------------------------------
[10:31:10.396] INFO: Expecting 208000 events.
[10:31:26.489] INFO: 208000 events read in total (15566ms).
[10:31:26.496] INFO: Test took 16710ms.
[10:31:27.016] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:27.380] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:31:27.380] INFO: number of red-efficiency pixels: 492 366 696 1243 995 1520 1385 748 896 1272 1186 1065 785 662 92 118
[10:31:27.380] INFO: number of X-ray hits detected: 225120 145792 211246 333119 345891 377611 361351 259131 286985 379765 372652 315828 310683 192974 75393 80472
[10:31:27.380] 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:31:27.380] INFO: number of Vcal hits detected: 207442 207601 207034 206360 206748 205882 206085 207082 206934 206356 206386 206584 207085 207128 207906 207879
[10:31:27.380] INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.6 99.3 99.5 99.1 99.2 99.6 99.5 99.3 99.3 99.4 99.6 99.6 100.0 99.9
[10:31:27.380] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.5 99.2 99.4 99.0 99.1 99.6 99.5 99.2 99.2 99.3 99.6 99.6 100.0 99.9
[10:31:27.380] INFO: X-ray hit rate [MHz/cm2]: 66.0 42.7 61.9 97.6 101.4 110.7 105.9 76.0 84.1 111.3 109.2 92.6 91.1 56.6 22.1 23.6
[10:31:27.380] INFO: PixTestHighRate::doXPixelAlive() done
[10:31:27.428] INFO: PixTest:: pg_setup set to default.
[10:31:27.441] INFO: enter test to run
[10:31:30.287] INFO: test: exit no parameter change
[10:31:30.664] QUIET: Connection to board 33 closed.
[10:31:30.677] INFO: pXar: this is the end, my friend