[10:31:35.843] INFO: *** Welcome to pxar ***
[10:31:35.843] INFO: *** Today: 2016/05/25
[10:31:36.430] INFO: *** Version: v1.9.0-796-gef167-dirty
[10:31:36.430] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//dacParameters35_C15.dat
[10:31:36.494] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:31:36.494] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//defaultMaskFile.dat
[10:31:36.496] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C15.dat
[10:31:36.591] INFO: clk: 4
[10:31:36.591] INFO: ctr: 4
[10:31:36.591] INFO: sda: 19
[10:31:36.591] INFO: tin: 9
[10:31:36.591] INFO: level: 15
[10:31:36.591] INFO: triggerdelay: 0
[10:31:36.591] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[10:31:36.591] INFO: Log level: INFO
[10:31:36.609] QUIET: Connection to board DTB_WREK4U opened.
[10:31:36.612] 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:
------------------------------------------------------
[10:31:36.615] INFO: RPC call hashes of host and DTB match: 398089610
[10:31:38.147] INFO: DUT info:
[10:31:38.147] INFO: The DUT currently contains the following objects:
[10:31:38.147] INFO: 2 TBM Cores tbm08c (2 ON)
[10:31:38.157] INFO: TBM Core alpha (0): 7 registers set
[10:31:38.157] INFO: TBM Core beta (1): 7 registers set
[10:31:38.157] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:31:38.157] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.157] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.157] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.157] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.158] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.158] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.158] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.158] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.158] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.158] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.158] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.158] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.158] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.158] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.158] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.158] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:31:38.603] INFO: enter 'restricted' command line mode
[10:31:38.603] INFO: enter test to run
[10:31:48.437] INFO: test: PixelAlive no parameter change
[10:31:48.437] INFO: running: pixelalive
[10:31:48.504] INFO: ----------------------------------------------------------------------
[10:31:48.504] 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:31:48.504] INFO: ----------------------------------------------------------------------
[10:31:48.823] INFO: Expecting 41600 events.
[10:31:53.197] INFO: 41600 events read in total (3655ms).
[10:31:53.365] INFO: Test took 4856ms.
[10:31:53.378] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:53.931] INFO: PixTestAlive::aliveTest() done
[10:31:53.931] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 2 0 0 0 0 0 0 0
[10:31:54.086] INFO: enter test to run
[10:32:18.385] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:32:18.385] INFO: running: highrate
[10:32:18.385] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:32:18.653] INFO: ----------------------------------------------------------------------
[10:32:18.653] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:32:18.653] INFO: ----------------------------------------------------------------------
[10:32:18.653] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:32:18.653] INFO: edge/corner pixel THR is adjusted
[10:32:18.653] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:32:19.613] INFO: Collecting data for 5 seconds...
[10:32:24.629] INFO: Done with hot pixel readout
[10:32:37.339] INFO: PixTest:: pg_setup set to default.





[10:32:37.368] INFO: 175 hot pixels found in step 0
[10:32:38.363] INFO: Collecting data for 5 seconds...
[10:32:43.381] INFO: Done with hot pixel readout
[10:32:55.987] INFO: PixTest:: pg_setup set to default.





[10:32:55.988] INFO: 180 hot pixels found in step 1
[10:32:56.984] INFO: Collecting data for 5 seconds...
[10:33:02.005] INFO: Done with hot pixel readout
[10:33:14.914] INFO: PixTest:: pg_setup set to default.









[10:33:14.915] INFO: 159 hot pixels found in step 2
[10:33:15.914] INFO: Collecting data for 5 seconds...
[10:33:20.934] INFO: Done with hot pixel readout
[10:33:33.685] INFO: PixTest:: pg_setup set to default.












[10:33:33.686] INFO: 145 hot pixels found in step 3
[10:33:34.684] INFO: Collecting data for 5 seconds...
[10:33:39.705] INFO: Done with hot pixel readout
[10:33:52.701] INFO: PixTest:: pg_setup set to default.
















[10:33:52.703] INFO: 137 hot pixels found in step 4
[10:33:53.701] INFO: Collecting data for 5 seconds...
[10:33:58.718] INFO: Done with hot pixel readout
[10:34:11.569] INFO: PixTest:: pg_setup set to default.











[10:34:11.571] INFO: 118 hot pixels found in step 5
[10:34:12.570] INFO: Collecting data for 5 seconds...
[10:34:17.588] INFO: Done with hot pixel readout
[10:34:30.503] INFO: PixTest:: pg_setup set to default.













[10:34:30.505] INFO: 104 hot pixels found in step 6
[10:34:31.503] INFO: Collecting data for 5 seconds...
[10:34:36.521] INFO: Done with hot pixel readout
[10:34:49.248] INFO: PixTest:: pg_setup set to default.



















[10:34:49.249] INFO: 117 hot pixels found in step 7
[10:34:50.247] INFO: Collecting data for 5 seconds...
[10:34:55.264] INFO: Done with hot pixel readout
[10:35:07.983] INFO: PixTest:: pg_setup set to default.














[10:35:07.984] INFO: 83 hot pixels found in step 8
[10:35:08.982] INFO: Collecting data for 5 seconds...
[10:35:13.998] INFO: Done with hot pixel readout
[10:35:26.734] INFO: PixTest:: pg_setup set to default.

















[10:35:26.736] INFO: 82 hot pixels found in step 9
[10:35:27.733] INFO: Collecting data for 5 seconds...
[10:35:32.750] INFO: Done with hot pixel readout
[10:35:45.444] INFO: PixTest:: pg_setup set to default.


















[10:35:45.446] INFO: 77 hot pixels found in step 10
[10:35:46.443] INFO: Collecting data for 5 seconds...
[10:35:51.459] INFO: Done with hot pixel readout
[10:36:03.942] INFO: PixTest:: pg_setup set to default.















[10:36:03.943] INFO: 73 hot pixels found in step 11
[10:36:04.942] INFO: Collecting data for 5 seconds...
[10:36:09.958] INFO: Done with hot pixel readout
[10:36:22.679] INFO: PixTest:: pg_setup set to default.














[10:36:22.681] INFO: 67 hot pixels found in step 12
[10:36:23.679] INFO: Collecting data for 5 seconds...
[10:36:28.696] INFO: Done with hot pixel readout
[10:36:41.413] INFO: PixTest:: pg_setup set to default.














[10:36:41.414] INFO: 84 hot pixels found in step 13
[10:36:42.412] INFO: Collecting data for 5 seconds...
[10:36:47.428] INFO: Done with hot pixel readout
[10:37:00.163] INFO: PixTest:: pg_setup set to default.










[10:37:00.164] INFO: 69 hot pixels found in step 14
[10:37:00.206] INFO: 69 hot pixels could not be trimmed and have been masked.
[10:37:00.229] INFO: PixTest::trimHotPixels() done
[10:37:00.229] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C0.dat
[10:37:00.241] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C1.dat
[10:37:00.265] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C2.dat
[10:37:00.277] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C3.dat
[10:37:00.282] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C4.dat
[10:37:00.288] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C5.dat
[10:37:00.293] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C6.dat
[10:37:00.298] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C7.dat
[10:37:00.303] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C8.dat
[10:37:00.308] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C9.dat
[10:37:00.314] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C10.dat
[10:37:00.319] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C11.dat
[10:37:00.324] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C12.dat
[10:37:00.329] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C13.dat
[10:37:00.335] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C14.dat
[10:37:00.340] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C15.dat
[10:37:00.345] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//defaultMaskFile.dat
[10:37:00.357] INFO: enter test to run
[10:37:39.378] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:37:39.378] INFO: running: highrate
[10:37:39.383] INFO: ----------------------------------------------------------------------
[10:37:39.383] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:37:39.383] INFO: ----------------------------------------------------------------------
[10:37:39.383] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:37:39.383] INFO: edge/corner pixel THR is adjusted
[10:37:39.383] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:37:40.341] INFO: Collecting data for 1 seconds...
[10:37:41.345] INFO: Done with hot pixel readout
[10:37:45.850] INFO: PixTest:: pg_setup set to default.
[10:37:45.851] INFO: 0 hot pixels found in step 0
[10:37:45.856] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:37:45.930] INFO: PixTest::trimHotPixels() done
[10:37:45.930] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C0.dat
[10:37:45.938] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C1.dat
[10:37:45.943] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C2.dat
[10:37:45.948] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C3.dat
[10:37:45.953] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C4.dat
[10:37:45.959] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C5.dat
[10:37:45.964] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C6.dat
[10:37:45.969] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C7.dat
[10:37:45.974] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C8.dat
[10:37:45.980] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C9.dat
[10:37:45.985] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C10.dat
[10:37:45.990] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C11.dat
[10:37:45.995] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C12.dat
[10:37:45.000] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C13.dat
[10:37:46.006] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C14.dat
[10:37:46.011] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//trimParameters35_C15.dat
[10:37:46.016] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-B-NZ_FPIXTest-17C-FNAL-160512-1436_2016-05-12_14h37m_1463081836/000_FPIXTest_p17//defaultMaskFile.dat
[10:37:46.026] INFO: enter test to run
[10:38:01.570] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:38:01.570] INFO: running: xray
[10:38:01.572] INFO: ----------------------------------------------------------------------
[10:38:01.572] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:38:01.572] INFO: ----------------------------------------------------------------------
[10:38:02.547] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:38:12.952] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[10:38:42.505] INFO: Resuming triggers.
[10:38:52.909] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[10:39:22.599] INFO: Resuming triggers.
[10:39:32.000] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[10:40:02.611] INFO: Resuming triggers.
[10:40:13.017] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[10:40:41.913] INFO: Resuming triggers.
[10:40:52.315] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[10:41:21.764] INFO: Resuming triggers.
[10:41:32.171] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[10:42:00.677] INFO: Resuming triggers.
[10:42:11.086] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[10:42:38.704] INFO: Resuming triggers.
[10:42:49.108] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[10:43:18.078] INFO: Resuming triggers.
[10:43:28.487] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[10:43:57.644] INFO: Resuming triggers.
[10:44:04.316] INFO: data taking finished, elapsed time: 100 seconds.
[10:44:23.357] INFO: PixTest:: pg_setup set to default.
[10:44:23.360] INFO: PixTestXray::doPhRun() done
[10:44:23.505] INFO: enter test to run
[10:46:17.279] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:46:17.279] INFO: running: xray
[10:46:17.280] INFO: ----------------------------------------------------------------------
[10:46:17.280] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:46:17.280] INFO: ----------------------------------------------------------------------
[10:46:18.244] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:46:24.036] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[10:46:53.714] INFO: Resuming triggers.
[10:46:59.501] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:47:29.182] INFO: Resuming triggers.
[10:47:34.969] INFO: run duration 17 seconds, buffer almost full (81%), pausing triggers.
[10:48:04.457] INFO: Resuming triggers.
[10:48:10.246] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:48:39.932] INFO: Resuming triggers.
[10:48:45.719] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[10:49:14.517] INFO: Resuming triggers.
[10:49:20.301] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:49:49.827] INFO: Resuming triggers.
[10:49:55.614] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[10:50:25.202] INFO: Resuming triggers.
[10:50:30.983] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:51:01.068] INFO: Resuming triggers.
[10:51:06.857] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[10:51:36.973] INFO: Resuming triggers.
[10:51:42.756] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[10:52:12.710] INFO: Resuming triggers.
[10:52:18.495] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[10:52:49.358] INFO: Resuming triggers.
[10:52:55.147] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:53:25.471] INFO: Resuming triggers.
[10:53:31.258] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[10:54:01.344] INFO: Resuming triggers.
[10:54:07.130] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:54:37.280] INFO: Resuming triggers.
[10:54:43.068] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[10:55:14.832] INFO: Resuming triggers.
[10:55:20.620] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:55:52.159] INFO: Resuming triggers.
[10:55:57.941] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[10:56:29.115] INFO: Resuming triggers.
[10:56:31.071] INFO: data taking finished, elapsed time: 100 seconds.
[10:56:41.534] INFO: PixTest:: pg_setup set to default.
[10:56:41.538] INFO: PixTestXray::doPhRun() done
[10:56:41.686] INFO: enter test to run
[10:56:58.274] INFO: test: HighRate no parameter change
[10:56:58.274] INFO: running: highrate
[10:56:58.275] INFO: ----------------------------------------------------------------------
[10:56:58.275] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:56:58.275] INFO: ----------------------------------------------------------------------
[10:56:58.417] INFO: Expecting 768 events.
[10:56:59.551] INFO: 768 events read in total (418ms).
[10:56:59.551] INFO: Test took 1269ms.
[10:57:00.354] INFO: Expecting 41600 events.
[10:57:03.491] INFO: 41600 events read in total (2610ms).
[10:57:03.492] INFO: Test took 3932ms.
[10:57:03.533] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:04.245] INFO: Expecting 41600 events.
[10:57:07.502] INFO: 41600 events read in total (2731ms).
[10:57:07.503] INFO: Test took 3949ms.
[10:57:07.544] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:08.252] INFO: Expecting 41600 events.
[10:57:11.535] INFO: 41600 events read in total (2756ms).
[10:57:11.536] INFO: Test took 3972ms.
[10:57:11.577] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:12.285] INFO: Expecting 41600 events.
[10:57:15.585] INFO: 41600 events read in total (2773ms).
[10:57:15.586] INFO: Test took 3989ms.
[10:57:15.627] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:16.339] INFO: Expecting 41600 events.
[10:57:19.652] INFO: 41600 events read in total (2786ms).
[10:57:19.653] INFO: Test took 4006ms.
[10:57:19.695] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:20.406] INFO: Expecting 41600 events.
[10:57:23.717] INFO: 41600 events read in total (2784ms).
[10:57:23.718] INFO: Test took 4003ms.
[10:57:23.759] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:24.468] INFO: Expecting 41600 events.
[10:57:27.784] INFO: 41600 events read in total (2789ms).
[10:57:27.785] INFO: Test took 4004ms.
[10:57:27.827] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:28.535] INFO: Expecting 41600 events.
[10:57:31.854] INFO: 41600 events read in total (2792ms).
[10:57:31.856] INFO: Test took 4007ms.
[10:57:31.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:32.607] INFO: Expecting 41600 events.
[10:57:35.934] INFO: 41600 events read in total (2800ms).
[10:57:35.935] INFO: Test took 4016ms.
[10:57:35.976] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:36.683] INFO: Expecting 41600 events.
[10:57:40.011] INFO: 41600 events read in total (2801ms).
[10:57:40.012] INFO: Test took 4016ms.
[10:57:40.054] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:40.761] INFO: Expecting 41600 events.
[10:57:44.081] INFO: 41600 events read in total (2793ms).
[10:57:44.082] INFO: Test took 4007ms.
[10:57:44.123] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:44.833] INFO: Expecting 41600 events.
[10:57:48.160] INFO: 41600 events read in total (2800ms).
[10:57:48.162] INFO: Test took 4017ms.
[10:57:48.203] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:48.910] INFO: Expecting 41600 events.
[10:57:52.231] INFO: 41600 events read in total (2794ms).
[10:57:52.232] INFO: Test took 4009ms.
[10:57:52.273] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:52.985] INFO: Expecting 41600 events.
[10:57:56.300] INFO: 41600 events read in total (2789ms).
[10:57:56.301] INFO: Test took 4007ms.
[10:57:56.342] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:57.054] INFO: Expecting 41600 events.
[10:58:00.366] INFO: 41600 events read in total (2785ms).
[10:58:00.367] INFO: Test took 4004ms.
[10:58:00.408] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:01.118] INFO: Expecting 41600 events.
[10:58:04.434] INFO: 41600 events read in total (2789ms).
[10:58:04.435] INFO: Test took 4006ms.
[10:58:04.476] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:05.185] INFO: Expecting 41600 events.
[10:58:08.492] INFO: 41600 events read in total (2780ms).
[10:58:08.493] INFO: Test took 3997ms.
[10:58:08.535] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:09.246] INFO: Expecting 41600 events.
[10:58:12.568] INFO: 41600 events read in total (2795ms).
[10:58:12.569] INFO: Test took 4013ms.
[10:58:12.610] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:13.317] INFO: Expecting 41600 events.
[10:58:16.589] INFO: 41600 events read in total (2746ms).
[10:58:16.591] INFO: Test took 3962ms.
[10:58:16.632] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:17.345] INFO: Expecting 41600 events.
[10:58:20.499] INFO: 41600 events read in total (2628ms).
[10:58:20.500] INFO: Test took 3848ms.
[10:58:20.541] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:20.893] INFO: enter test to run
[10:58:42.985] INFO: test: HighRate no parameter change
[10:58:42.985] INFO: running: highrate
[10:58:42.986] INFO: ----------------------------------------------------------------------
[10:58:42.986] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:58:42.986] INFO: ----------------------------------------------------------------------
[10:58:43.604] INFO: Expecting 208000 events.
[10:58:55.907] INFO: 208000 events read in total (11776ms).
[10:58:55.911] INFO: Test took 12915ms.
[10:58:56.096] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:56.363] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 2 0 0 0 0 0 0 0
[10:58:56.363] INFO: number of red-efficiency pixels: 104 46 97 204 177 201 198 150 110 159 148 124 157 80 25 31
[10:58:56.363] INFO: number of X-ray hits detected: 78283 55237 85565 143733 148635 146210 146660 97223 96077 125734 123713 111973 111330 63371 29105 34399
[10:58:56.363] 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:58:56.363] INFO: number of Vcal hits detected: 207895 207952 207902 207790 207816 207790 207792 207847 207790 207840 207846 207874 207838 207918 207975 207969
[10:58:56.363] 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.9 99.9 99.9 100.0 100.0 100.0
[10:58:56.363] INFO: Vcal hit overall efficiency (%): 99.9 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[10:58:56.363] INFO: X-ray hit rate [MHz/cm2]: 22.9 16.2 25.1 42.1 43.6 42.9 43.0 28.5 28.2 36.9 36.3 32.8 32.6 18.6 8.5 10.1
[10:58:56.363] INFO: PixTestHighRate::doXPixelAlive() done
[10:58:56.410] INFO: PixTest:: pg_setup set to default.
[10:58:56.427] INFO: enter test to run
[11:00:10.393] INFO: test: HighRate no parameter change
[11:00:10.393] INFO: running: highrate
[11:00:10.394] INFO: ----------------------------------------------------------------------
[11:00:10.394] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:00:10.394] INFO: ----------------------------------------------------------------------
[11:00:11.002] INFO: Expecting 208000 events.
[11:00:25.254] INFO: 208000 events read in total (13726ms).
[11:00:25.261] INFO: Test took 14860ms.
[11:00:25.631] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:00:25.955] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 2 0 0 0 0 0 0 0
[11:00:25.955] INFO: number of red-efficiency pixels: 258 165 371 678 735 657 778 467 328 476 574 475 563 272 86 97
[11:00:25.955] INFO: number of X-ray hits detected: 162807 114269 178591 296497 306374 302203 301981 200781 199011 258814 254783 231622 230963 130671 60348 71513
[11:00:25.955] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:00:25.955] INFO: number of Vcal hits detected: 207722 207827 207573 207235 207156 207252 207088 207461 207558 207479 207372 207491 207372 207703 207912 207901
[11:00:25.955] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.7 99.6 99.8 99.8 99.8 99.7 99.8 99.7 99.9 100.0 100.0
[11:00:25.955] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.6 99.6 99.6 99.6 99.7 99.8 99.7 99.7 99.8 99.7 99.9 100.0 100.0
[11:00:25.955] INFO: X-ray hit rate [MHz/cm2]: 47.7 33.5 52.3 86.9 89.8 88.6 88.5 58.9 58.3 75.9 74.7 67.9 67.7 38.3 17.7 21.0
[11:00:25.956] INFO: PixTestHighRate::doXPixelAlive() done
[11:00:26.006] INFO: PixTest:: pg_setup set to default.
[11:00:26.019] INFO: enter test to run
[11:01:09.824] INFO: test: HighRate no parameter change
[11:01:09.824] INFO: running: highrate
[11:01:09.825] INFO: ----------------------------------------------------------------------
[11:01:09.825] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:01:09.825] INFO: ----------------------------------------------------------------------
[11:01:10.438] INFO: Expecting 208000 events.
[11:01:27.448] INFO: 208000 events read in total (16483ms).
[11:01:27.458] INFO: Test took 17623ms.
[11:01:28.049] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:01:28.440] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 2 0 0 0 0 0 0 0
[11:01:28.440] INFO: number of red-efficiency pixels: 605 344 794 1635 1815 1703 1803 1116 736 1204 1351 1046 1333 649 166 180
[11:01:28.441] INFO: number of X-ray hits detected: 249669 175806 273269 455053 470187 463194 462009 307967 306061 396854 391907 356052 354122 201571 93175 110305
[11:01:28.441] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:01:28.441] INFO: number of Vcal hits detected: 207231 207610 206842 205638 205297 205389 205108 206338 207021 206473 206132 206711 206271 207146 207821 207815
[11:01:28.441] INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.5 99.0 98.8 98.9 98.8 99.3 99.6 99.3 99.2 99.5 99.2 99.6 99.9 99.9
[11:01:28.441] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.4 98.9 98.7 98.7 98.6 99.2 99.5 99.3 99.1 99.4 99.2 99.6 99.9 99.9
[11:01:28.441] INFO: X-ray hit rate [MHz/cm2]: 73.2 51.5 80.1 133.4 137.8 135.8 135.4 90.3 89.7 116.3 114.9 104.4 103.8 59.1 27.3 32.3
[11:01:28.441] INFO: PixTestHighRate::doXPixelAlive() done
[11:01:28.488] INFO: PixTest:: pg_setup set to default.
[11:01:28.506] INFO: enter test to run
[11:01:31.352] INFO: test: exit no parameter change
[11:01:31.693] QUIET: Connection to board 32 closed.
[11:01:31.694] INFO: pXar: this is the end, my friend