[19:24:22.709] INFO: *** Welcome to pxar ***
[19:24:22.709] INFO: *** Today: 2016/03/23
[19:24:22.713] INFO: *** Version: prod-12-dirty
[19:24:22.713] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//dacParameters35_C15.dat
[19:24:22.741] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//tbmParameters_C0b.dat
[19:24:22.748] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//defaultMaskFile.dat
[19:24:22.748] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C15.dat
[19:24:22.839] INFO: clk: 4
[19:24:22.839] INFO: ctr: 4
[19:24:22.839] INFO: sda: 19
[19:24:22.839] INFO: tin: 9
[19:24:22.839] INFO: level: 15
[19:24:22.839] INFO: triggerdelay: 0
[19:24:22.839] QUIET: Instanciating API for pxar prod-12
[19:24:22.839] INFO: Log level: INFO
[19:24:22.857] QUIET: Connection to board DTB_WREK4U opened.
[19:24:22.861] 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:
------------------------------------------------------
[19:24:22.864] INFO: RPC call hashes of host and DTB match: 398089610
[19:24:24.397] INFO: DUT info:
[19:24:24.397] INFO: The DUT currently contains the following objects:
[19:24:24.397] INFO: 2 TBM Cores tbm08c (2 ON)
[19:24:24.397] INFO: TBM Core alpha (0): 7 registers set
[19:24:24.397] INFO: TBM Core beta (1): 7 registers set
[19:24:24.397] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[19:24:24.397] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.397] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.397] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.397] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.397] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.397] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.397] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.397] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.397] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.397] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.397] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.398] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.398] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.398] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.398] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.398] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[19:24:24.801] INFO: enter 'restricted' command line mode
[19:24:24.801] INFO: enter test to run
[19:24:30.525] INFO: test: PixelAlive no parameter change
[19:24:30.525] INFO: running: pixelalive
[19:24:30.534] INFO: ----------------------------------------------------------------------
[19:24:30.534] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[19:24:30.534] INFO: ----------------------------------------------------------------------
[19:24:30.852] INFO: Expecting 41600 events.
[19:24:35.137] INFO: 41600 events read in total (3567ms).
[19:24:35.303] INFO: Test took 4767ms.
[19:24:35.318] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:24:35.582] INFO: PixTestAlive::aliveTest() done
[19:24:35.582] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[19:24:35.613] INFO: enter test to run
[19:25:03.037] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=100;maskuntrimmable=0<-
[19:25:03.037] INFO: running: highrate
[19:25:03.037] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[19:25:03.238] INFO: ----------------------------------------------------------------------
[19:25:03.238] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[19:25:03.238] INFO: ----------------------------------------------------------------------
[19:25:03.238] INFO: THR = 100, corresponding to ~ 53.3333 MHz/cm2
[19:25:03.238] INFO: edge/corner pixel THR is adjusted
[19:25:03.238] INFO: PixTestHighRate::trimHotPixels: step 0...
[19:25:04.198] INFO: Collecting data for 5 seconds...
[19:25:09.215] INFO: Done with hot pixel readout


















































[19:25:20.044] INFO: PixTest:: pg_setup set to default.
















[19:25:20.063] INFO: 740 hot pixels found in step 0
[19:25:21.048] INFO: Collecting data for 5 seconds...
[19:25:26.065] INFO: Done with hot pixel readout
[19:25:37.180] INFO: PixTest:: pg_setup set to default.




















































[19:25:37.185] INFO: 1617 hot pixels found in step 1
[19:25:38.173] INFO: Collecting data for 5 seconds...
[19:25:43.189] INFO: Done with hot pixel readout
[19:25:54.314] INFO: PixTest:: pg_setup set to default.





















































































[19:25:54.319] INFO: 1505 hot pixels found in step 2
[19:25:55.305] INFO: Collecting data for 5 seconds...
[19:26:00.322] INFO: Done with hot pixel readout
[19:26:11.479] INFO: PixTest:: pg_setup set to default.




















































































































































[19:26:11.495] INFO: 1433 hot pixels found in step 3
[19:26:12.483] INFO: Collecting data for 5 seconds...
[19:26:17.500] INFO: Done with hot pixel readout
[19:26:28.613] INFO: PixTest:: pg_setup set to default.


















































































































































































































[19:26:28.630] INFO: 1327 hot pixels found in step 4
[19:26:29.618] INFO: Collecting data for 5 seconds...
[19:26:34.635] INFO: Done with hot pixel readout
[19:26:45.739] INFO: PixTest:: pg_setup set to default.















































































































































































































































































































[19:26:45.770] INFO: 1250 hot pixels found in step 5
[19:26:46.757] INFO: Collecting data for 5 seconds...
[19:26:51.773] INFO: Done with hot pixel readout
[19:27:02.923] INFO: PixTest:: pg_setup set to default.




































































































































































































































































































































































[19:27:02.956] INFO: 1200 hot pixels found in step 6
[19:27:03.948] INFO: Collecting data for 5 seconds...
[19:27:08.964] INFO: Done with hot pixel readout
[19:27:19.187] INFO: PixTest:: pg_setup set to default.






















































































































































































































































































































































































[19:27:19.219] INFO: 1107 hot pixels found in step 7
[19:27:20.206] INFO: Collecting data for 5 seconds...
[19:27:25.220] INFO: Done with hot pixel readout
[19:27:36.366] INFO: PixTest:: pg_setup set to default.






























































































































































































































































































































































































[19:27:36.403] INFO: 994 hot pixels found in step 8
[19:27:37.394] INFO: Collecting data for 5 seconds...
[19:27:42.410] INFO: Done with hot pixel readout
[19:27:53.501] INFO: PixTest:: pg_setup set to default.















































































































































































































































































































































































































































[19:27:53.562] INFO: 969 hot pixels found in step 9
[19:27:54.548] INFO: Collecting data for 5 seconds...
[19:27:59.558] INFO: Done with hot pixel readout
[19:28:09.833] INFO: PixTest:: pg_setup set to default.












































































































































































































































































































































































































































































[19:28:09.897] INFO: 942 hot pixels found in step 10
[19:28:10.887] INFO: Collecting data for 5 seconds...
[19:28:15.904] INFO: Done with hot pixel readout
[19:28:27.029] INFO: PixTest:: pg_setup set to default.















































































































































































































































































































































































































































































[19:28:27.092] INFO: 879 hot pixels found in step 11
[19:28:28.080] INFO: Collecting data for 5 seconds...
[19:28:33.098] INFO: Done with hot pixel readout
[19:28:43.841] INFO: PixTest:: pg_setup set to default.


































































































































































































































































































































































































































































































[19:28:43.907] INFO: 868 hot pixels found in step 12
[19:28:44.895] INFO: Collecting data for 5 seconds...
[19:28:49.913] INFO: Done with hot pixel readout
[19:29:00.688] INFO: PixTest:: pg_setup set to default.






















































































































































































































































































































































































































































































[19:29:00.753] INFO: 857 hot pixels found in step 13
[19:29:01.743] INFO: Collecting data for 5 seconds...
[19:29:06.760] INFO: Done with hot pixel readout
[19:29:17.432] INFO: PixTest:: pg_setup set to default.















































































































































[19:29:17.447] INFO: 171 hot pixels found in step 14
[19:29:17.482] INFO: PixTest::trimHotPixels() done
[19:29:17.502] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C0.dat
[19:29:17.513] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C1.dat
[19:29:17.521] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C2.dat
[19:29:17.528] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C3.dat
[19:29:17.535] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C4.dat
[19:29:17.543] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C5.dat
[19:29:17.550] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C6.dat
[19:29:17.557] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C7.dat
[19:29:17.565] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C8.dat
[19:29:17.572] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C9.dat
[19:29:17.579] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C10.dat
[19:29:17.586] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C11.dat
[19:29:17.593] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C12.dat
[19:29:17.600] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C13.dat
[19:29:17.608] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C14.dat
[19:29:17.615] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C15.dat
[19:29:17.640] INFO: enter test to run
[19:31:02.386] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=150;maskuntrimmable=0<-
[19:31:02.387] INFO: running: highrate
[19:31:02.391] INFO: ----------------------------------------------------------------------
[19:31:02.391] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[19:31:02.391] INFO: ----------------------------------------------------------------------
[19:31:02.391] INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[19:31:02.391] INFO: edge/corner pixel THR is adjusted
[19:31:02.391] INFO: PixTestHighRate::trimHotPixels: step 0...
[19:31:03.349] INFO: Collecting data for 1 seconds...
[19:31:04.352] INFO: Done with hot pixel readout
[19:31:08.345] INFO: PixTest:: pg_setup set to default.
[19:31:08.345] INFO: 0 hot pixels found in step 0
[19:31:08.443] INFO: PixTest::trimHotPixels() done
[19:31:08.443] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C0.dat
[19:31:08.454] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C1.dat
[19:31:08.461] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C2.dat
[19:31:08.468] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C3.dat
[19:31:08.476] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C4.dat
[19:31:08.483] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C5.dat
[19:31:08.490] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C6.dat
[19:31:08.497] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C7.dat
[19:31:08.504] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C8.dat
[19:31:08.512] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C9.dat
[19:31:08.519] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C10.dat
[19:31:08.526] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C11.dat
[19:31:08.533] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C12.dat
[19:31:08.541] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C13.dat
[19:31:08.548] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C14.dat
[19:31:08.555] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//trimParameters35_C15.dat
[19:31:08.572] INFO: enter test to run
[19:31:35.026] INFO: test: Xray setting parameters: ->savemaskfile=1<-
[19:31:35.026] INFO: running: xray
[19:31:35.031] INFO: ----------------------------------------------------------------------
[19:31:35.031] INFO: PixTest::maskHotPixels() running for 10 seconds with 100 kHz trigger rate
[19:31:35.031] INFO: ----------------------------------------------------------------------
[19:31:35.988] INFO: PixTestHighRate::maskHotPixels start TriggerLoop with period 404 and duration 10 seconds and trigger rate 100 kHz
[19:31:45.032] INFO: Done with hot pixel readout
[19:31:59.450] INFO: PixTest:: pg_setup set to default.
[19:31:59.451] INFO: 222 hot pixels found!
[19:31:59.451] INFO: PixTest::maskHotPixels() done
[19:31:59.455] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-H-2-41_FPIXTest-17C-FNAL-160129-1021_2016-01-29_10h21m_1454084474/000_FPIXTest_p17//defaultMaskFile.dat
[19:31:59.624] INFO: enter test to run
[19:32:28.298] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[19:32:28.298] INFO: running: xray
[19:32:28.299] INFO: ----------------------------------------------------------------------
[19:32:28.299] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[19:32:28.299] INFO: ----------------------------------------------------------------------
[19:32:29.263] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[19:32:41.517] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[19:33:10.510] INFO: Resuming triggers.
[19:33:22.765] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[19:33:51.801] INFO: Resuming triggers.
[19:34:04.051] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[19:34:33.161] INFO: Resuming triggers.
[19:34:45.407] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[19:35:14.617] INFO: Resuming triggers.
[19:35:26.865] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[19:35:55.000] INFO: Resuming triggers.
[19:36:08.566] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.





















[19:36:37.549] INFO: Resuming triggers.
[19:36:49.795] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[19:37:18.986] INFO: Resuming triggers.
[19:37:31.228] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[19:38:00.502] INFO: Resuming triggers.
[19:38:02.507] INFO: data taking finished, elapsed time: 100 seconds.
[19:38:06.761] INFO: PixTest:: pg_setup set to default.
[19:38:06.765] INFO: PixTestXray::doPhRun() done
[19:38:06.898] INFO: enter test to run
[19:39:19.514] INFO: test: HighRate no parameter change
[19:39:19.514] INFO: running: highrate
[19:39:19.515] INFO: ----------------------------------------------------------------------
[19:39:19.515] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[19:39:19.515] INFO: ----------------------------------------------------------------------
[19:39:19.655] INFO: Expecting 768 events.
[19:39:20.787] INFO: 768 events read in total (417ms).
[19:39:20.788] INFO: Test took 1267ms.
[19:39:21.591] INFO: Expecting 41600 events.
[19:39:24.676] INFO: 41600 events read in total (2558ms).
[19:39:24.677] INFO: Test took 3882ms.
[19:39:24.705] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:39:25.438] INFO: Expecting 41600 events.
[19:39:28.603] INFO: 41600 events read in total (2638ms).
[19:39:28.604] INFO: Test took 3884ms.
[19:39:28.633] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:39:29.367] INFO: Expecting 41600 events.
[19:39:32.542] INFO: 41600 events read in total (2648ms).
[19:39:32.543] INFO: Test took 3893ms.
[19:39:32.572] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:39:33.307] INFO: Expecting 41600 events.
[19:39:36.487] INFO: 41600 events read in total (2653ms).
[19:39:36.488] INFO: Test took 3899ms.
[19:39:36.518] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:39:37.251] INFO: Expecting 41600 events.
[19:39:40.423] INFO: 41600 events read in total (2645ms).
[19:39:40.424] INFO: Test took 3889ms.
[19:39:40.453] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:39:41.185] INFO: Expecting 41600 events.
[19:39:44.370] INFO: 41600 events read in total (2658ms).
[19:39:44.371] INFO: Test took 3901ms.
[19:39:44.401] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:39:45.133] INFO: Expecting 41600 events.
[19:39:48.323] INFO: 41600 events read in total (2663ms).
[19:39:48.323] INFO: Test took 3904ms.
[19:39:48.352] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:39:49.088] INFO: Expecting 41600 events.
[19:39:52.276] INFO: 41600 events read in total (2661ms).
[19:39:52.277] INFO: Test took 3908ms.
[19:39:52.306] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:39:53.035] INFO: Expecting 41600 events.
[19:39:56.095] INFO: 41600 events read in total (2533ms).
[19:39:56.096] INFO: Test took 3775ms.
[19:39:56.125] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:39:56.858] INFO: Expecting 41600 events.
[19:40:00.054] INFO: 41600 events read in total (2669ms).
[19:40:00.055] INFO: Test took 3914ms.
[19:40:00.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:40:00.814] INFO: Expecting 41600 events.
[19:40:03.966] INFO: 41600 events read in total (2625ms).
[19:40:03.967] INFO: Test took 3865ms.
[19:40:03.996] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:40:04.727] INFO: Expecting 41600 events.
[19:40:07.915] INFO: 41600 events read in total (2661ms).
[19:40:07.916] INFO: Test took 3902ms.
[19:40:07.945] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:40:08.676] INFO: Expecting 41600 events.
[19:40:11.867] INFO: 41600 events read in total (2665ms).
[19:40:11.868] INFO: Test took 3905ms.
[19:40:11.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:40:12.632] INFO: Expecting 41600 events.
[19:40:15.845] INFO: 41600 events read in total (2686ms).
[19:40:15.846] INFO: Test took 3931ms.
[19:40:15.875] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:40:16.605] INFO: Expecting 41600 events.
[19:40:19.773] INFO: 41600 events read in total (2641ms).
[19:40:19.774] INFO: Test took 3883ms.
[19:40:19.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:40:20.538] INFO: Expecting 41600 events.
[19:40:23.730] INFO: 41600 events read in total (2666ms).
[19:40:23.731] INFO: Test took 3911ms.
[19:40:23.760] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:40:24.494] INFO: Expecting 41600 events.
[19:40:27.612] INFO: 41600 events read in total (2591ms).
[19:40:27.613] INFO: Test took 3835ms.
[19:40:27.642] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:40:28.377] INFO: Expecting 41600 events.
[19:40:31.543] INFO: 41600 events read in total (2639ms).
[19:40:31.543] INFO: Test took 3885ms.
[19:40:31.572] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:40:32.303] INFO: Expecting 41600 events.
[19:40:35.441] INFO: 41600 events read in total (2611ms).
[19:40:35.442] INFO: Test took 3852ms.
[19:40:35.471] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:40:36.204] INFO: Expecting 41600 events.
[19:40:39.173] INFO: 41600 events read in total (2442ms).
[19:40:39.174] INFO: Test took 3686ms.
[19:40:39.201] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:40:39.577] INFO: enter test to run
[19:40:50.152] INFO: test: HighRate no parameter change
[19:40:50.152] INFO: running: highrate
[19:40:50.153] INFO: ----------------------------------------------------------------------
[19:40:50.153] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[19:40:50.153] INFO: ----------------------------------------------------------------------
[19:40:50.768] INFO: Expecting 208000 events.
[19:41:01.966] INFO: 208000 events read in total (10671ms).
[19:41:01.969] INFO: Test took 11808ms.
[19:41:02.087] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:41:02.331] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[19:41:02.331] INFO: number of red-efficiency pixels: 41 32 81 104 105 108 118 99 87 101 86 1730 82 48 30 27
[19:41:02.331] INFO: number of X-ray hits detected: 51783 38032 55150 77032 85062 91076 89454 69308 65263 80268 78539 82140 64985 40866 19581 23368
[19:41:02.331] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[19:41:02.331] INFO: number of Vcal hits detected: 207959 207968 207916 207895 207894 207890 207880 207898 207912 207895 207914 206201 207916 207951 207970 207973
[19:41:02.331] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 99.1 100.0 100.0 100.0 100.0
[19:41:02.331] 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 100.0 99.1 100.0 100.0 100.0 100.0
[19:41:02.331] INFO: X-ray hit rate [MHz/cm2]: 15.2 11.1 16.2 22.6 24.9 26.7 26.2 20.3 19.1 23.5 23.0 24.1 19.0 12.0 5.7 6.8
[19:41:02.331] INFO: PixTestHighRate::doXPixelAlive() done
[19:41:02.377] INFO: PixTest:: pg_setup set to default.
[19:41:02.392] INFO: enter test to run
[19:41:25.615] INFO: test: HighRate no parameter change
[19:41:25.615] INFO: running: highrate
[19:41:25.616] INFO: ----------------------------------------------------------------------
[19:41:25.616] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[19:41:25.616] INFO: ----------------------------------------------------------------------
[19:41:26.231] INFO: Expecting 208000 events.
[19:41:39.792] INFO: 208000 events read in total (13034ms).
[19:41:39.797] INFO: Test took 14172ms.
[19:41:40.080] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:41:40.379] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[19:41:40.379] INFO: number of red-efficiency pixels: 194 153 287 419 472 485 513 393 286 335 346 1176 263 194 37 63
[19:41:40.379] INFO: number of X-ray hits detected: 130083 94795 137462 193496 212930 226127 222281 174272 162543 201146 197642 195441 164002 102380 48966 59666
[19:41:40.379] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[19:41:40.379] INFO: number of Vcal hits detected: 207797 207841 207678 207554 207495 207454 207442 207576 207705 207639 207637 206660 207725 207800 207960 207936
[19:41:40.379] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.9 99.8 99.8 99.4 99.9 99.9 100.0 100.0
[19:41:40.379] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.7 99.7 99.8 99.9 99.8 99.8 99.4 99.9 99.9 100.0 100.0
[19:41:40.379] INFO: X-ray hit rate [MHz/cm2]: 38.1 27.8 40.3 56.7 62.4 66.3 65.2 51.1 47.6 59.0 57.9 57.3 48.1 30.0 14.4 17.5
[19:41:40.379] INFO: PixTestHighRate::doXPixelAlive() done
[19:41:40.431] INFO: PixTest:: pg_setup set to default.
[19:41:40.446] INFO: enter test to run
[19:42:01.823] INFO: test: HighRate no parameter change
[19:42:01.823] INFO: running: highrate
[19:42:01.824] INFO: ----------------------------------------------------------------------
[19:42:01.824] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[19:42:01.824] INFO: ----------------------------------------------------------------------
[19:42:02.434] INFO: Expecting 208000 events.
[19:42:18.126] INFO: 208000 events read in total (15165ms).
[19:42:18.133] INFO: Test took 16301ms.
[19:42:18.565] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:42:18.911] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[19:42:18.911] INFO: number of red-efficiency pixels: 349 330 703 979 1180 1272 1281 906 673 841 823 1289 726 400 111 149
[19:42:18.911] INFO: number of X-ray hits detected: 202896 149503 215847 302786 334036 355633 346013 273287 255616 316066 309336 304027 257365 161046 76762 92430
[19:42:18.911] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[19:42:18.911] INFO: number of Vcal hits detected: 207615 207633 207083 206801 206525 206325 206326 206829 207226 206979 207035 206315 207167 207522 207883 207851
[19:42:18.911] INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.6 99.5 99.4 99.3 99.3 99.5 99.7 99.5 99.6 99.3 99.6 99.8 99.9 99.9
[19:42:18.911] INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.6 99.4 99.3 99.2 99.2 99.4 99.6 99.5 99.5 99.2 99.6 99.8 99.9 99.9
[19:42:18.911] INFO: X-ray hit rate [MHz/cm2]: 59.5 43.8 63.3 88.7 97.9 104.2 101.4 80.1 74.9 92.6 90.7 89.1 75.4 47.2 22.5 27.1
[19:42:18.911] INFO: PixTestHighRate::doXPixelAlive() done
[19:42:18.957] INFO: PixTest:: pg_setup set to default.
[19:42:18.976] INFO: enter test to run
[19:42:26.151] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[19:42:26.151] INFO: running: xray
[19:42:26.152] INFO: ----------------------------------------------------------------------
[19:42:26.152] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[19:42:26.152] INFO: ----------------------------------------------------------------------
[19:42:27.117] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[19:42:33.768] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[19:43:03.778] INFO: Resuming triggers.
[19:43:10.431] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[19:43:40.547] INFO: Resuming triggers.
[19:43:47.204] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[19:44:17.106] INFO: Resuming triggers.
[19:44:23.760] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[19:44:53.863] INFO: Resuming triggers.
[19:45:00.518] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[19:45:30.570] INFO: Resuming triggers.
[19:45:37.220] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[19:46:07.280] INFO: Resuming triggers.
[19:46:13.933] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[19:46:44.007] INFO: Resuming triggers.
[19:46:50.659] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[19:47:20.706] INFO: Resuming triggers.
[19:47:27.355] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[19:47:55.750] INFO: Resuming triggers.
[19:48:02.399] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[19:48:29.844] INFO: Resuming triggers.
[19:48:36.491] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[19:49:06.570] INFO: Resuming triggers.
[19:49:13.220] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[19:49:43.276] INFO: Resuming triggers.
[19:49:49.928] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[19:50:20.013] INFO: Resuming triggers.
[19:50:26.661] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[19:50:56.642] INFO: Resuming triggers.
[19:51:03.291] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[19:51:33.259] INFO: Resuming triggers.
[19:51:33.819] INFO: data taking finished, elapsed time: 100 seconds.
[19:51:36.708] INFO: PixTest:: pg_setup set to default.
[19:51:36.711] INFO: PixTestXray::doPhRun() done
[19:51:36.864] INFO: enter test to run
[19:52:33.070] INFO: test: exit no parameter change
[19:52:33.369] QUIET: Connection to board 32 closed.
[19:52:33.370] INFO: pXar: this is the end, my friend