[11:45:16.084] INFO: *** Welcome to pxar ***
[11:45:16.084] INFO: *** Today: 2016/05/04
[11:45:18.688] INFO: *** Version: v1.9.0-796-gef167-dirty
[11:45:18.688] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//dacParameters35_C15.dat
[11:45:19.088] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:45:19.088] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//defaultMaskFile.dat
[11:45:19.109] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C15.dat
[11:45:19.987] INFO: clk: 4
[11:45:19.987] INFO: ctr: 4
[11:45:19.987] INFO: sda: 19
[11:45:19.987] INFO: tin: 9
[11:45:19.987] INFO: level: 15
[11:45:19.987] INFO: triggerdelay: 0
[11:45:19.987] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[11:45:19.987] INFO: Log level: INFO
[11:45:20.033] QUIET: Connection to board DTB_WREK4U opened.
[11:45:20.037] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[11:45:20.040] INFO: RPC call hashes of host and DTB match: 398089610
[11:45:21.584] INFO: DUT info:
[11:45:21.584] INFO: The DUT currently contains the following objects:
[11:45:21.584] INFO: 2 TBM Cores tbm08c (2 ON)
[11:45:21.584] INFO: TBM Core alpha (0): 7 registers set
[11:45:21.584] INFO: TBM Core beta (1): 7 registers set
[11:45:21.584] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:45:21.584] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:21.584] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:21.584] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:21.584] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:21.584] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:21.584] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:21.584] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:21.584] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:21.584] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:21.584] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:21.584] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:21.584] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:21.584] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:21.584] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:21.584] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:21.584] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:22.044] INFO: enter 'restricted' command line mode
[11:45:22.044] INFO: enter test to run
[11:45:25.179] INFO: test: PixelAlive no parameter change
[11:45:25.179] INFO: running: pixelalive
[11:45:25.195] INFO: enter test to run
[11:45:32.347] INFO: test: PixelAlive no parameter change
[11:45:32.347] INFO: running: pixelalive
[11:45:32.522] INFO: ----------------------------------------------------------------------
[11:45:32.522] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:45:32.522] INFO: ----------------------------------------------------------------------
[11:45:32.893] INFO: Expecting 41600 events.
[11:45:36.977] INFO: 41600 events read in total (3351ms).
[11:45:37.146] INFO: Test took 4621ms.
[11:45:37.154] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:45:39.927] INFO: PixTestAlive::aliveTest() done
[11:45:39.927] INFO: number of dead pixels (per ROC): 0 3 0 0 0 0 0 0 0 0 0 0 2 0 0 0
[11:45:40.781] INFO: enter test to run
[11:45:53.962] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[11:45:53.962] INFO: running: highrate
[11:45:54.025] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[11:45:55.361] INFO: ----------------------------------------------------------------------
[11:45:55.361] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[11:45:55.361] INFO: ----------------------------------------------------------------------
[11:45:55.361] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[11:45:55.361] INFO: edge/corner pixel THR is adjusted
[11:45:55.361] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:45:56.320] INFO: Collecting data for 5 seconds...
[11:46:01.334] INFO: Done with hot pixel readout
[11:46:11.596] INFO: PixTest:: pg_setup set to default.
[11:46:11.597] INFO: 16 hot pixels found in step 0
[11:46:12.589] INFO: Collecting data for 5 seconds...
[11:46:17.602] INFO: Done with hot pixel readout
[11:46:27.726] INFO: PixTest:: pg_setup set to default.

[11:46:27.727] INFO: 17 hot pixels found in step 1
[11:46:28.723] INFO: Collecting data for 5 seconds...
[11:46:33.737] INFO: Done with hot pixel readout
[11:46:43.676] INFO: PixTest:: pg_setup set to default.
[11:46:43.677] INFO: 18 hot pixels found in step 2
[11:46:44.672] INFO: Collecting data for 5 seconds...
[11:46:49.685] INFO: Done with hot pixel readout
[11:46:59.837] INFO: PixTest:: pg_setup set to default.

[11:46:59.838] INFO: 18 hot pixels found in step 3
[11:47:00.834] INFO: Collecting data for 5 seconds...
[11:47:05.847] INFO: Done with hot pixel readout
[11:47:16.184] INFO: PixTest:: pg_setup set to default.
[11:47:16.185] INFO: 16 hot pixels found in step 4
[11:47:17.181] INFO: Collecting data for 5 seconds...
[11:47:22.193] INFO: Done with hot pixel readout
[11:47:32.159] INFO: PixTest:: pg_setup set to default.
[11:47:32.160] INFO: 10 hot pixels found in step 5
[11:47:33.157] INFO: Collecting data for 5 seconds...
[11:47:38.170] INFO: Done with hot pixel readout
[11:47:48.295] INFO: PixTest:: pg_setup set to default.
[11:47:48.296] INFO: 20 hot pixels found in step 6
[11:47:49.291] INFO: Collecting data for 5 seconds...
[11:47:54.306] INFO: Done with hot pixel readout
[11:48:04.985] INFO: PixTest:: pg_setup set to default.
[11:48:04.986] INFO: 15 hot pixels found in step 7
[11:48:05.985] INFO: Collecting data for 5 seconds...
[11:48:10.998] INFO: Done with hot pixel readout
[11:48:21.181] INFO: PixTest:: pg_setup set to default.

[11:48:21.182] INFO: 15 hot pixels found in step 8
[11:48:22.178] INFO: Collecting data for 5 seconds...
[11:48:27.190] INFO: Done with hot pixel readout
[11:48:37.275] INFO: PixTest:: pg_setup set to default.
[11:48:37.276] INFO: 11 hot pixels found in step 9
[11:48:38.271] INFO: Collecting data for 5 seconds...
[11:48:43.283] INFO: Done with hot pixel readout
[11:48:53.733] INFO: PixTest:: pg_setup set to default.
[11:48:53.734] INFO: 9 hot pixels found in step 10
[11:48:54.731] INFO: Collecting data for 5 seconds...
[11:48:59.744] INFO: Done with hot pixel readout
[11:49:09.917] INFO: PixTest:: pg_setup set to default.

[11:49:09.918] INFO: 7 hot pixels found in step 11
[11:49:10.914] INFO: Collecting data for 5 seconds...
[11:49:15.926] INFO: Done with hot pixel readout
[11:49:25.843] INFO: PixTest:: pg_setup set to default.
[11:49:25.844] INFO: 13 hot pixels found in step 12
[11:49:26.840] INFO: Collecting data for 5 seconds...
[11:49:31.852] INFO: Done with hot pixel readout
[11:49:41.803] INFO: PixTest:: pg_setup set to default.

[11:49:41.804] INFO: 9 hot pixels found in step 13
[11:49:42.801] INFO: Collecting data for 5 seconds...
[11:49:47.814] INFO: Done with hot pixel readout
[11:49:57.918] INFO: PixTest:: pg_setup set to default.


[11:49:57.919] INFO: 13 hot pixels found in step 14
[11:49:57.959] INFO: 13 hot pixels could not be trimmed and have been masked.
[11:49:58.112] INFO: PixTest::trimHotPixels() done
[11:49:58.112] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C0.dat
[11:49:58.120] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C1.dat
[11:49:58.126] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C2.dat
[11:49:58.131] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C3.dat
[11:49:58.137] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C4.dat
[11:49:58.142] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C5.dat
[11:49:58.148] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C6.dat
[11:49:58.153] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C7.dat
[11:49:58.158] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C8.dat
[11:49:58.164] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C9.dat
[11:49:58.169] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C10.dat
[11:49:58.175] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C11.dat
[11:49:58.180] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C12.dat
[11:49:58.185] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C13.dat
[11:49:58.191] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C14.dat
[11:49:58.196] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C15.dat
[11:49:58.202] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//defaultMaskFile.dat
[11:49:58.212] INFO: enter test to run
[11:50:53.864] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[11:50:53.864] INFO: running: highrate
[11:50:53.868] INFO: ----------------------------------------------------------------------
[11:50:53.868] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:50:53.868] INFO: ----------------------------------------------------------------------
[11:50:53.868] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:50:53.868] INFO: edge/corner pixel THR is adjusted
[11:50:53.868] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:50:54.825] INFO: Collecting data for 1 seconds...
[11:50:55.828] INFO: Done with hot pixel readout
[11:50:59.717] INFO: PixTest:: pg_setup set to default.
[11:50:59.717] INFO: 0 hot pixels found in step 0
[11:50:59.723] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:50:59.804] INFO: PixTest::trimHotPixels() done
[11:50:59.804] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C0.dat
[11:50:59.816] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C1.dat
[11:50:59.824] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C2.dat
[11:50:59.830] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C3.dat
[11:50:59.835] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C4.dat
[11:50:59.840] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C5.dat
[11:50:59.845] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C6.dat
[11:50:59.851] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C7.dat
[11:50:59.856] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C8.dat
[11:50:59.862] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C9.dat
[11:50:59.867] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C10.dat
[11:50:59.872] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C11.dat
[11:50:59.878] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C12.dat
[11:50:59.883] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C13.dat
[11:50:59.888] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C14.dat
[11:50:59.894] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C15.dat
[11:50:59.899] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-O-NT_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//defaultMaskFile.dat
[11:50:59.945] INFO: enter test to run
[11:51:31.056] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:51:31.056] INFO: running: xray
[11:51:31.077] INFO: ----------------------------------------------------------------------
[11:51:31.077] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:51:31.077] INFO: ----------------------------------------------------------------------
[11:51:32.096] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:51:43.011] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[11:52:07.896] INFO: Resuming triggers.
[11:52:18.813] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[11:52:44.070] INFO: Resuming triggers.
[11:52:54.987] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[11:53:20.144] INFO: Resuming triggers.
[11:53:31.060] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[11:53:55.619] INFO: Resuming triggers.
[11:54:06.535] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[11:54:32.106] INFO: Resuming triggers.
[11:54:43.024] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[11:55:09.216] INFO: Resuming triggers.
[11:55:20.135] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[11:55:44.503] INFO: Resuming triggers.
[11:55:55.419] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[11:56:20.029] INFO: Resuming triggers.
[11:56:30.947] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[11:56:55.296] INFO: Resuming triggers.
[11:56:57.290] INFO: data taking finished, elapsed time: 100 seconds.
[11:57:02.044] INFO: PixTest:: pg_setup set to default.
[11:57:02.047] INFO: PixTestXray::doPhRun() done
[11:57:02.370] INFO: enter test to run
[11:57:39.421] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:57:39.421] INFO: running: xray
[11:57:39.422] INFO: ----------------------------------------------------------------------
[11:57:39.422] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:57:39.422] INFO: ----------------------------------------------------------------------
[11:57:40.396] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:57:46.497] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[11:58:11.791] INFO: Resuming triggers.
[11:58:17.890] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[11:58:43.224] INFO: Resuming triggers.
[11:58:49.325] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[11:59:15.808] INFO: Resuming triggers.
[11:59:21.905] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[11:59:49.749] INFO: Resuming triggers.
[11:59:55.851] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[12:00:22.289] INFO: Resuming triggers.
[12:00:28.389] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[12:00:54.277] INFO: Resuming triggers.
[12:01:00.378] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[12:01:30.566] INFO: Resuming triggers.
[12:01:36.666] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[12:02:07.477] INFO: Resuming triggers.
[12:02:13.576] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[12:02:44.832] INFO: Resuming triggers.
[12:02:50.933] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[12:03:21.036] INFO: Resuming triggers.
[12:03:27.139] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[12:03:57.231] INFO: Resuming triggers.
[12:04:03.332] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[12:04:33.486] INFO: Resuming triggers.
[12:04:39.588] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[12:05:09.670] INFO: Resuming triggers.
[12:05:15.770] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[12:05:45.873] INFO: Resuming triggers.
[12:05:51.973] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[12:06:22.164] INFO: Resuming triggers.
[12:06:28.264] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[12:06:58.401] INFO: Resuming triggers.
[12:07:01.085] INFO: data taking finished, elapsed time: 100 seconds.
[12:07:14.689] INFO: PixTest:: pg_setup set to default.
[12:07:14.692] INFO: PixTestXray::doPhRun() done
[12:07:14.840] INFO: enter test to run
[12:08:19.945] INFO: test: HighRate no parameter change
[12:08:19.945] INFO: running: highrate
[12:08:19.961] INFO: ----------------------------------------------------------------------
[12:08:19.961] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:08:19.961] INFO: ----------------------------------------------------------------------
[12:08:20.125] INFO: Expecting 768 events.
[12:08:21.263] INFO: 768 events read in total (419ms).
[12:08:21.263] INFO: Test took 1272ms.
[12:08:22.067] INFO: Expecting 41600 events.
[12:08:25.196] INFO: 41600 events read in total (2602ms).
[12:08:25.197] INFO: Test took 3903ms.
[12:08:25.235] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:25.953] INFO: Expecting 41600 events.
[12:08:29.172] INFO: 41600 events read in total (2693ms).
[12:08:29.173] INFO: Test took 3918ms.
[12:08:29.212] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:29.925] INFO: Expecting 41600 events.
[12:08:33.184] INFO: 41600 events read in total (2732ms).
[12:08:33.185] INFO: Test took 3953ms.
[12:08:33.225] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:33.938] INFO: Expecting 41600 events.
[12:08:37.229] INFO: 41600 events read in total (2764ms).
[12:08:37.230] INFO: Test took 3985ms.
[12:08:37.270] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:37.983] INFO: Expecting 41600 events.
[12:08:41.275] INFO: 41600 events read in total (2765ms).
[12:08:41.276] INFO: Test took 3987ms.
[12:08:41.315] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:42.031] INFO: Expecting 41600 events.
[12:08:45.319] INFO: 41600 events read in total (2761ms).
[12:08:45.320] INFO: Test took 3984ms.
[12:08:45.359] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:46.076] INFO: Expecting 41600 events.
[12:08:49.372] INFO: 41600 events read in total (2769ms).
[12:08:49.373] INFO: Test took 3993ms.
[12:08:49.412] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:50.124] INFO: Expecting 41600 events.
[12:08:53.428] INFO: 41600 events read in total (2777ms).
[12:08:53.429] INFO: Test took 3997ms.
[12:08:53.469] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:54.183] INFO: Expecting 41600 events.
[12:08:57.471] INFO: 41600 events read in total (2761ms).
[12:08:57.472] INFO: Test took 3983ms.
[12:08:57.512] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:08:58.225] INFO: Expecting 41600 events.
[12:09:01.523] INFO: 41600 events read in total (2771ms).
[12:09:01.524] INFO: Test took 3991ms.
[12:09:01.563] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:02.271] INFO: Expecting 41600 events.
[12:09:05.561] INFO: 41600 events read in total (2763ms).
[12:09:05.563] INFO: Test took 3981ms.
[12:09:05.601] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:06.318] INFO: Expecting 41600 events.
[12:09:09.615] INFO: 41600 events read in total (2770ms).
[12:09:09.616] INFO: Test took 3996ms.
[12:09:09.655] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:10.368] INFO: Expecting 41600 events.
[12:09:13.670] INFO: 41600 events read in total (2776ms).
[12:09:13.671] INFO: Test took 3996ms.
[12:09:13.711] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:14.421] INFO: Expecting 41600 events.
[12:09:17.717] INFO: 41600 events read in total (2769ms).
[12:09:17.718] INFO: Test took 3988ms.
[12:09:17.758] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:18.471] INFO: Expecting 41600 events.
[12:09:21.772] INFO: 41600 events read in total (2774ms).
[12:09:21.773] INFO: Test took 3995ms.
[12:09:21.813] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:22.525] INFO: Expecting 41600 events.
[12:09:25.820] INFO: 41600 events read in total (2768ms).
[12:09:25.821] INFO: Test took 3989ms.
[12:09:25.861] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:26.572] INFO: Expecting 41600 events.
[12:09:29.881] INFO: 41600 events read in total (2782ms).
[12:09:29.882] INFO: Test took 4002ms.
[12:09:29.922] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:30.634] INFO: Expecting 41600 events.
[12:09:33.932] INFO: 41600 events read in total (2771ms).
[12:09:33.933] INFO: Test took 3989ms.
[12:09:33.974] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:34.687] INFO: Expecting 41600 events.
[12:09:38.070] INFO: 41600 events read in total (2857ms).
[12:09:38.071] INFO: Test took 4078ms.
[12:09:38.113] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:38.825] INFO: Expecting 41600 events.
[12:09:41.002] INFO: 41600 events read in total (2651ms).
[12:09:41.003] INFO: Test took 3870ms.
[12:09:42.042] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:09:42.396] INFO: enter test to run
[12:10:44.104] INFO: test: HighRate no parameter change
[12:10:44.104] INFO: running: highrate
[12:10:44.105] INFO: ----------------------------------------------------------------------
[12:10:44.105] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:10:44.105] INFO: ----------------------------------------------------------------------
[12:10:44.725] INFO: Expecting 208000 events.
[12:10:56.842] INFO: 208000 events read in total (11590ms).
[12:10:56.846] INFO: Test took 12731ms.
[12:10:57.024] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:57.286] INFO: number of dead pixels (per ROC): 0 3 0 0 0 0 0 0 0 0 68 0 2 0 0 0
[12:10:57.287] INFO: number of red-efficiency pixels: 72 68 95 152 199 215 196 145 125 122 250 141 113 65 24 39
[12:10:57.287] INFO: number of X-ray hits detected: 80780 53026 82120 131901 146801 143144 136477 100874 90941 106239 111177 97460 98579 59109 27050 27566
[12:10:57.287] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:10:57.287] INFO: number of Vcal hits detected: 207922 207783 207902 207843 207800 207777 207797 207849 207872 207876 203500 207856 207788 207935 207974 207961
[12:10:57.287] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.5 99.9 99.9 100.0 100.0 100.0
[12:10:57.287] INFO: Vcal hit overall efficiency (%): 100.0 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 97.8 99.9 99.9 100.0 100.0 100.0
[12:10:57.287] INFO: X-ray hit rate [MHz/cm2]: 23.7 15.5 24.1 38.7 43.0 42.0 40.0 29.6 26.7 31.1 32.6 28.6 28.9 17.3 7.9 8.1
[12:10:57.287] INFO: PixTestHighRate::doXPixelAlive() done
[12:10:57.332] INFO: PixTest:: pg_setup set to default.
[12:10:57.343] INFO: enter test to run
[12:12:00.007] INFO: test: HighRate no parameter change
[12:12:00.008] INFO: running: highrate
[12:12:00.009] INFO: ----------------------------------------------------------------------
[12:12:00.009] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:12:00.009] INFO: ----------------------------------------------------------------------
[12:12:00.629] INFO: Expecting 208000 events.
[12:12:14.970] INFO: 208000 events read in total (13814ms).
[12:12:14.976] INFO: Test took 14957ms.
[12:12:15.332] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:12:15.651] INFO: number of dead pixels (per ROC): 0 3 0 0 0 0 0 0 0 0 20 0 2 0 0 0
[12:12:15.651] INFO: number of red-efficiency pixels: 228 191 322 549 700 804 744 485 331 420 633 377 326 245 72 66
[12:12:15.651] INFO: number of X-ray hits detected: 164855 108754 167807 268169 298628 291014 276360 204908 185868 217550 226501 199898 201479 121666 55234 56259
[12:12:15.651] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:12:15.651] INFO: number of Vcal hits detected: 207764 207651 207645 207358 207181 207070 207138 207456 207640 207544 203156 207597 207563 207737 207924 207932
[12:12:15.651] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.6 99.6 99.8 99.8 99.8 98.1 99.8 99.8 99.9 100.0 100.0
[12:12:15.651] INFO: Vcal hit overall efficiency (%): 99.9 99.8 99.8 99.7 99.6 99.6 99.6 99.7 99.8 99.8 97.7 99.8 99.8 99.9 100.0 100.0
[12:12:15.651] INFO: X-ray hit rate [MHz/cm2]: 48.3 31.9 49.2 78.6 87.5 85.3 81.0 60.1 54.5 63.8 66.4 58.6 59.1 35.7 16.2 16.5
[12:12:15.651] INFO: PixTestHighRate::doXPixelAlive() done
[12:12:15.698] INFO: PixTest:: pg_setup set to default.
[12:12:15.714] INFO: enter test to run
[12:12:52.495] INFO: test: HighRate no parameter change
[12:12:52.495] INFO: running: highrate
[12:12:52.496] INFO: ----------------------------------------------------------------------
[12:12:52.496] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:12:52.496] INFO: ----------------------------------------------------------------------
[12:12:53.115] INFO: Expecting 208000 events.
[12:13:09.584] INFO: 208000 events read in total (15942ms).
[12:13:09.592] INFO: Test took 17087ms.
[12:13:10.115] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:13:10.488] INFO: number of dead pixels (per ROC): 0 3 0 0 0 0 0 0 0 0 2 0 2 0 0 0
[12:13:10.488] INFO: number of red-efficiency pixels: 556 415 688 1245 1674 1784 1658 963 790 954 1256 934 682 539 122 103
[12:13:10.488] INFO: number of X-ray hits detected: 247930 162494 249489 399745 445354 433336 412428 304965 276356 323484 336959 297381 300600 180367 82494 83866
[12:13:10.488] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:13:10.488] INFO: number of Vcal hits detected: 207348 207378 207097 206354 205605 205340 205578 206730 207079 206878 199969 206869 207115 207334 207870 207895
[12:13:10.488] INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.6 99.3 99.0 98.8 99.0 99.5 99.6 99.5 96.1 99.5 99.7 99.7 99.9 100.0
[12:13:10.488] INFO: Vcal hit overall efficiency (%): 99.7 99.7 99.6 99.2 98.8 98.7 98.8 99.4 99.6 99.5 96.1 99.5 99.6 99.7 99.9 99.9
[12:13:10.488] INFO: X-ray hit rate [MHz/cm2]: 72.7 47.6 73.1 117.2 130.5 127.0 120.9 89.4 81.0 94.8 98.8 87.2 88.1 52.9 24.2 24.6
[12:13:10.488] INFO: PixTestHighRate::doXPixelAlive() done
[12:13:10.534] INFO: PixTest:: pg_setup set to default.
[12:13:10.552] INFO: enter test to run
[12:13:27.343] INFO: test: exit no parameter change
[12:13:27.952] QUIET: Connection to board 32 closed.
[12:13:28.081] INFO: pXar: this is the end, my friend