[18:33:26.701] INFO: *** Welcome to pxar ***
[18:33:26.701] INFO: *** Today: 2016/09/28
[18:33:27.414] INFO: *** Version: v1.9.0-818-g96727
[18:33:27.414] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//dacParameters35_C15.dat
[18:33:27.461] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//tbmParameters_C0b.dat
[18:33:27.461] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//defaultMaskFile.dat
[18:33:27.461] INFO: MASKED Roc 0 col/row: 1 5
[18:33:27.462] INFO: MASKED Roc 0 col/row: 1 37
[18:33:27.462] INFO: MASKED Roc 0 col/row: 5 34
[18:33:27.462] INFO: MASKED Roc 0 col/row: 11 30
[18:33:27.462] INFO: MASKED Roc 0 col/row: 12 32
[18:33:27.462] INFO: MASKED Roc 0 col/row: 26 20
[18:33:27.462] INFO: MASKED Roc 0 col/row: 30 17
[18:33:27.462] INFO: MASKED Roc 1 col/row: 3 24
[18:33:27.462] INFO: MASKED Roc 1 col/row: 9 36
[18:33:27.462] INFO: MASKED Roc 3 col/row: 13 15
[18:33:27.462] INFO: MASKED Roc 4 col/row: 4 15
[18:33:27.462] INFO: MASKED Roc 5 col/row: 2 26
[18:33:27.462] INFO: MASKED Roc 5 col/row: 12 20
[18:33:27.462] INFO: MASKED Roc 5 col/row: 13 55
[18:33:27.462] INFO: MASKED Roc 5 col/row: 23 15
[18:33:27.462] INFO: MASKED Roc 5 col/row: 23 20
[18:33:27.462] INFO: MASKED Roc 5 col/row: 27 13
[18:33:27.462] INFO: MASKED Roc 5 col/row: 33 17
[18:33:27.462] INFO: MASKED Roc 5 col/row: 47 17
[18:33:27.462] INFO: MASKED Roc 6 col/row: 1 30
[18:33:27.462] INFO: MASKED Roc 6 col/row: 13 17
[18:33:27.462] INFO: MASKED Roc 6 col/row: 14 57
[18:33:27.462] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C15.dat
[18:33:27.462] INFO: masking Roc 0 col/row: 1 5
[18:33:27.462] INFO: masking Roc 0 col/row: 1 37
[18:33:27.462] INFO: masking Roc 0 col/row: 5 34
[18:33:27.462] INFO: masking Roc 0 col/row: 11 30
[18:33:27.462] INFO: masking Roc 0 col/row: 12 32
[18:33:27.462] INFO: masking Roc 0 col/row: 26 20
[18:33:27.462] INFO: masking Roc 0 col/row: 30 17
[18:33:27.467] INFO: masking Roc 1 col/row: 3 24
[18:33:27.467] INFO: masking Roc 1 col/row: 9 36
[18:33:27.475] INFO: masking Roc 3 col/row: 13 15
[18:33:27.480] INFO: masking Roc 4 col/row: 4 15
[18:33:27.484] INFO: masking Roc 5 col/row: 2 26
[18:33:27.484] INFO: masking Roc 5 col/row: 12 20
[18:33:27.484] INFO: masking Roc 5 col/row: 13 55
[18:33:27.484] INFO: masking Roc 5 col/row: 23 15
[18:33:27.484] INFO: masking Roc 5 col/row: 23 20
[18:33:27.484] INFO: masking Roc 5 col/row: 27 13
[18:33:27.484] INFO: masking Roc 5 col/row: 33 17
[18:33:27.484] INFO: masking Roc 5 col/row: 47 17
[18:33:27.489] INFO: masking Roc 6 col/row: 1 30
[18:33:27.489] INFO: masking Roc 6 col/row: 13 17
[18:33:27.489] INFO: masking Roc 6 col/row: 14 57
[18:33:27.536] INFO: clk: 4
[18:33:27.536] INFO: ctr: 4
[18:33:27.536] INFO: sda: 19
[18:33:27.536] INFO: tin: 9
[18:33:27.536] INFO: level: 15
[18:33:27.536] INFO: triggerdelay: 0
[18:33:27.536] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[18:33:27.536] INFO: Log level: INFO
[18:33:27.554] QUIET: Connection to board DTB_WREKRL opened.
[18:33:27.558] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[18:33:27.561] INFO: RPC call hashes of host and DTB match: 398089610
[18:33:29.090] INFO: DUT info:
[18:33:29.090] INFO: The DUT currently contains the following objects:
[18:33:29.090] INFO: 2 TBM Cores tbm08c (2 ON)
[18:33:29.090] INFO: TBM Core alpha (0): 7 registers set
[18:33:29.090] INFO: TBM Core beta (1): 7 registers set
[18:33:29.090] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[18:33:29.091] INFO: ROC 0: 19 DACs set, Pixels: 7 masked, 0 active.
[18:33:29.091] INFO: ROC 1: 19 DACs set, Pixels: 2 masked, 0 active.
[18:33:29.091] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[18:33:29.091] INFO: ROC 3: 19 DACs set, Pixels: 1 masked, 0 active.
[18:33:29.091] INFO: ROC 4: 19 DACs set, Pixels: 1 masked, 0 active.
[18:33:29.091] INFO: ROC 5: 19 DACs set, Pixels: 8 masked, 0 active.
[18:33:29.091] INFO: ROC 6: 19 DACs set, Pixels: 3 masked, 0 active.
[18:33:29.091] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[18:33:29.091] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[18:33:29.091] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[18:33:29.091] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[18:33:29.091] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[18:33:29.091] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[18:33:29.091] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[18:33:29.092] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[18:33:29.092] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[18:33:29.495] INFO: enter 'restricted' command line mode
[18:33:29.495] INFO: enter test to run
[18:33:37.874] INFO: test: PixelAlive no parameter change
[18:33:37.874] INFO: running: pixelalive
[18:33:37.883] INFO: ----------------------------------------------------------------------
[18:33:37.883] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[18:33:37.883] INFO: ----------------------------------------------------------------------
[18:33:37.886] INFO: ROC 0 masking pixel 1/5
[18:33:37.886] INFO: ROC 0 masking pixel 1/37
[18:33:37.886] INFO: ROC 0 masking pixel 5/34
[18:33:37.886] INFO: ROC 0 masking pixel 11/30
[18:33:37.886] INFO: ROC 0 masking pixel 12/32
[18:33:37.886] INFO: ROC 0 masking pixel 26/20
[18:33:37.886] INFO: ROC 0 masking pixel 30/17
[18:33:37.886] INFO: ROC 1 masking pixel 3/24
[18:33:37.886] INFO: ROC 1 masking pixel 9/36
[18:33:37.886] INFO: ROC 3 masking pixel 13/15
[18:33:37.886] INFO: ROC 4 masking pixel 4/15
[18:33:37.886] INFO: ROC 5 masking pixel 2/26
[18:33:37.886] INFO: ROC 5 masking pixel 12/20
[18:33:37.886] INFO: ROC 5 masking pixel 13/55
[18:33:37.886] INFO: ROC 5 masking pixel 23/15
[18:33:37.886] INFO: ROC 5 masking pixel 23/20
[18:33:37.886] INFO: ROC 5 masking pixel 27/13
[18:33:37.886] INFO: ROC 5 masking pixel 33/17
[18:33:37.886] INFO: ROC 5 masking pixel 47/17
[18:33:37.886] INFO: ROC 6 masking pixel 1/30
[18:33:37.886] INFO: ROC 6 masking pixel 13/17
[18:33:37.886] INFO: ROC 6 masking pixel 14/57
[18:33:38.201] INFO: Expecting 41600 events.
[18:33:42.531] INFO: 41600 events read in total (3611ms).
[18:33:42.700] INFO: Test took 4814ms.
[18:33:42.712] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:33:43.193] INFO: PixTestAlive::aliveTest() done
[18:33:43.193] INFO: number of dead pixels (per ROC): 8 2 2 1 1 8 5 1 0 0 0 0 0 0 0 0
[18:33:43.193] INFO: ROC 0 masking pixel 1/5
[18:33:43.193] INFO: ROC 0 masking pixel 1/37
[18:33:43.193] INFO: ROC 0 masking pixel 5/34
[18:33:43.193] INFO: ROC 0 masking pixel 11/30
[18:33:43.194] INFO: ROC 0 masking pixel 12/32
[18:33:43.194] INFO: ROC 0 masking pixel 26/20
[18:33:43.194] INFO: ROC 0 masking pixel 30/17
[18:33:43.194] INFO: ROC 1 masking pixel 3/24
[18:33:43.194] INFO: ROC 1 masking pixel 9/36
[18:33:43.194] INFO: ROC 3 masking pixel 13/15
[18:33:43.194] INFO: ROC 4 masking pixel 4/15
[18:33:43.194] INFO: ROC 5 masking pixel 2/26
[18:33:43.194] INFO: ROC 5 masking pixel 12/20
[18:33:43.194] INFO: ROC 5 masking pixel 13/55
[18:33:43.194] INFO: ROC 5 masking pixel 23/15
[18:33:43.194] INFO: ROC 5 masking pixel 23/20
[18:33:43.194] INFO: ROC 5 masking pixel 27/13
[18:33:43.194] INFO: ROC 5 masking pixel 33/17
[18:33:43.194] INFO: ROC 5 masking pixel 47/17
[18:33:43.194] INFO: ROC 6 masking pixel 1/30
[18:33:43.194] INFO: ROC 6 masking pixel 13/17
[18:33:43.194] INFO: ROC 6 masking pixel 14/57
[18:33:43.224] INFO: enter test to run
[18:35:13.154] INFO: test: timing no parameter change
[18:35:13.154] INFO: running: timing
[18:35:13.157] INFO: ######################################################################
[18:35:13.157] INFO: PixTestTiming::doTest()
[18:35:13.157] INFO: ######################################################################
[18:35:13.158] INFO: ----------------------------------------------------------------------
[18:35:13.158] INFO: PixTestTiming::TBMPhaseScan()
[18:35:13.158] INFO: ----------------------------------------------------------------------
[18:41:19.263] INFO: TBM Phase Settings: 20
[18:41:19.263] INFO: 400MHz Phase: 5
[18:41:19.264] INFO: 160MHz Phase: 0
[18:41:19.264] INFO: Functional Phase Area: 3
[18:41:19.267] INFO: Test took 366110 ms.
[18:41:19.267] INFO: PixTestTiming::TBMPhaseScan() done.
[18:41:19.267] INFO: ROC 0 masking pixel 1/5
[18:41:19.267] INFO: ROC 0 masking pixel 1/37
[18:41:19.267] INFO: ROC 0 masking pixel 5/34
[18:41:19.267] INFO: ROC 0 masking pixel 11/30
[18:41:19.267] INFO: ROC 0 masking pixel 12/32
[18:41:19.267] INFO: ROC 0 masking pixel 26/20
[18:41:19.267] INFO: ROC 0 masking pixel 30/17
[18:41:19.267] INFO: ROC 1 masking pixel 3/24
[18:41:19.267] INFO: ROC 1 masking pixel 9/36
[18:41:19.267] INFO: ROC 3 masking pixel 13/15
[18:41:19.267] INFO: ROC 4 masking pixel 4/15
[18:41:19.267] INFO: ROC 5 masking pixel 2/26
[18:41:19.267] INFO: ROC 5 masking pixel 12/20
[18:41:19.267] INFO: ROC 5 masking pixel 13/55
[18:41:19.267] INFO: ROC 5 masking pixel 23/15
[18:41:19.267] INFO: ROC 5 masking pixel 23/20
[18:41:19.267] INFO: ROC 5 masking pixel 27/13
[18:41:19.267] INFO: ROC 5 masking pixel 33/17
[18:41:19.267] INFO: ROC 5 masking pixel 47/17
[18:41:19.267] INFO: ROC 6 masking pixel 1/30
[18:41:19.267] INFO: ROC 6 masking pixel 13/17
[18:41:19.267] INFO: ROC 6 masking pixel 14/57
[18:41:19.268] INFO: ----------------------------------------------------------------------
[18:41:19.268] INFO: PixTestTiming::ROCDelayScan()
[18:41:19.268] INFO: ----------------------------------------------------------------------
[18:43:24.613] INFO: ROC Delay Settings: 228
[18:43:24.613] INFO: ROC Header-Trailer/Token Delay: 11
[18:43:24.613] INFO: ROC Port 0 Delay: 4
[18:43:24.613] INFO: ROC Port 1 Delay: 4
[18:43:24.613] INFO: Functional ROC Area: 5
[18:43:24.616] INFO: Test took 125349 ms.
[18:43:24.616] INFO: PixTestTiming::ROCDelayScan() done.
[18:43:24.616] INFO: ROC 0 masking pixel 1/5
[18:43:24.616] INFO: ROC 0 masking pixel 1/37
[18:43:24.616] INFO: ROC 0 masking pixel 5/34
[18:43:24.616] INFO: ROC 0 masking pixel 11/30
[18:43:24.616] INFO: ROC 0 masking pixel 12/32
[18:43:24.616] INFO: ROC 0 masking pixel 26/20
[18:43:24.616] INFO: ROC 0 masking pixel 30/17
[18:43:24.616] INFO: ROC 1 masking pixel 3/24
[18:43:24.616] INFO: ROC 1 masking pixel 9/36
[18:43:24.616] INFO: ROC 3 masking pixel 13/15
[18:43:24.616] INFO: ROC 4 masking pixel 4/15
[18:43:24.616] INFO: ROC 5 masking pixel 2/26
[18:43:24.616] INFO: ROC 5 masking pixel 12/20
[18:43:24.616] INFO: ROC 5 masking pixel 13/55
[18:43:24.617] INFO: ROC 5 masking pixel 23/15
[18:43:24.617] INFO: ROC 5 masking pixel 23/20
[18:43:24.617] INFO: ROC 5 masking pixel 27/13
[18:43:24.617] INFO: ROC 5 masking pixel 33/17
[18:43:24.617] INFO: ROC 5 masking pixel 47/17
[18:43:24.617] INFO: ROC 6 masking pixel 1/30
[18:43:24.617] INFO: ROC 6 masking pixel 13/17
[18:43:24.617] INFO: ROC 6 masking pixel 14/57
[18:43:24.617] INFO: ----------------------------------------------------------------------
[18:43:24.617] INFO: PixTestTiming::TimingTest()
[18:43:24.617] INFO: ----------------------------------------------------------------------
[18:43:40.955] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:43:56.088] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:44:11.291] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:44:26.436] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:44:41.454] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:44:56.631] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:45:11.782] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:45:27.008] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:45:42.054] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:45:57.068] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:45:57.450] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:45:57.467] INFO: ----------------------------------------------------------------------
[18:45:57.467] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[18:45:57.468] INFO: ----------------------------------------------------------------------
[18:45:57.468] INFO: ----------------------------------------------------------------------
[18:45:57.468] INFO: Read back bit status: 1
[18:45:57.468] INFO: ----------------------------------------------------------------------
[18:45:57.468] INFO: ----------------------------------------------------------------------
[18:45:57.468] INFO: Timings are good!
[18:45:57.468] INFO: ----------------------------------------------------------------------
[18:45:57.468] INFO: Test took 152851 ms.
[18:45:57.468] INFO: PixTestTiming::TimingTest() done.
[18:45:57.468] INFO: ROC 0 masking pixel 1/5
[18:45:57.468] INFO: ROC 0 masking pixel 1/37
[18:45:57.468] INFO: ROC 0 masking pixel 5/34
[18:45:57.468] INFO: ROC 0 masking pixel 11/30
[18:45:57.468] INFO: ROC 0 masking pixel 12/32
[18:45:57.468] INFO: ROC 0 masking pixel 26/20
[18:45:57.468] INFO: ROC 0 masking pixel 30/17
[18:45:57.468] INFO: ROC 1 masking pixel 3/24
[18:45:57.468] INFO: ROC 1 masking pixel 9/36
[18:45:57.468] INFO: ROC 3 masking pixel 13/15
[18:45:57.468] INFO: ROC 4 masking pixel 4/15
[18:45:57.468] INFO: ROC 5 masking pixel 2/26
[18:45:57.468] INFO: ROC 5 masking pixel 12/20
[18:45:57.468] INFO: ROC 5 masking pixel 13/55
[18:45:57.468] INFO: ROC 5 masking pixel 23/15
[18:45:57.468] INFO: ROC 5 masking pixel 23/20
[18:45:57.468] INFO: ROC 5 masking pixel 27/13
[18:45:57.468] INFO: ROC 5 masking pixel 33/17
[18:45:57.468] INFO: ROC 5 masking pixel 47/17
[18:45:57.468] INFO: ROC 6 masking pixel 1/30
[18:45:57.468] INFO: ROC 6 masking pixel 13/17
[18:45:57.468] INFO: ROC 6 masking pixel 14/57
[18:45:57.468] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//tbmParameters_C0a.dat
[18:45:57.468] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//tbmParameters_C0b.dat
[18:45:57.469] INFO: PixTestTiming::doTest took 644315 ms.
[18:45:57.469] INFO: PixTestTiming::doTest() done
[18:45:57.469] INFO: ROC 0 masking pixel 1/5
[18:45:57.469] INFO: ROC 0 masking pixel 1/37
[18:45:57.469] INFO: ROC 0 masking pixel 5/34
[18:45:57.469] INFO: ROC 0 masking pixel 11/30
[18:45:57.469] INFO: ROC 0 masking pixel 12/32
[18:45:57.469] INFO: ROC 0 masking pixel 26/20
[18:45:57.469] INFO: ROC 0 masking pixel 30/17
[18:45:57.469] INFO: ROC 1 masking pixel 3/24
[18:45:57.469] INFO: ROC 1 masking pixel 9/36
[18:45:57.469] INFO: ROC 3 masking pixel 13/15
[18:45:57.469] INFO: ROC 4 masking pixel 4/15
[18:45:57.469] INFO: ROC 5 masking pixel 2/26
[18:45:57.469] INFO: ROC 5 masking pixel 12/20
[18:45:57.469] INFO: ROC 5 masking pixel 13/55
[18:45:57.469] INFO: ROC 5 masking pixel 23/15
[18:45:57.469] INFO: ROC 5 masking pixel 23/20
[18:45:57.469] INFO: ROC 5 masking pixel 27/13
[18:45:57.469] INFO: ROC 5 masking pixel 33/17
[18:45:57.469] INFO: ROC 5 masking pixel 47/17
[18:45:57.469] INFO: ROC 6 masking pixel 1/30
[18:45:57.469] INFO: ROC 6 masking pixel 13/17
[18:45:57.469] INFO: ROC 6 masking pixel 14/57
[18:45:57.469] INFO: Write out TBMPhaseScan_0_V0
[18:45:57.469] INFO: Write out TBMPhaseScan_1_V0
[18:45:57.469] INFO: Write out CombinedTBMPhaseScan_V0
[18:45:57.471] INFO: Write out ROCDelayScan3_V0
[18:45:57.471] INFO: enter test to run
[18:47:00.789] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[18:47:00.789] INFO: running: highrate
[18:47:00.789] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[18:47:00.951] INFO: ----------------------------------------------------------------------
[18:47:00.951] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[18:47:00.951] INFO: ----------------------------------------------------------------------
[18:47:00.951] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[18:47:00.951] INFO: edge/corner pixel THR is adjusted
[18:47:00.951] INFO: PixTestHighRate::trimHotPixels: step 0...
[18:47:01.908] INFO: Collecting data for 5 seconds...
[18:47:06.928] INFO: Done with hot pixel readout
[18:47:19.195] INFO: PixTest:: pg_setup set to default.





[18:47:19.196] INFO: 28 hot pixels found in step 0
[18:47:20.191] INFO: Collecting data for 5 seconds...
[18:47:25.212] INFO: Done with hot pixel readout
[18:47:37.297] INFO: PixTest:: pg_setup set to default.






[18:47:37.298] INFO: 22 hot pixels found in step 1
[18:47:38.294] INFO: Collecting data for 5 seconds...
[18:47:43.315] INFO: Done with hot pixel readout
[18:47:55.486] INFO: PixTest:: pg_setup set to default.



[18:47:55.487] INFO: 21 hot pixels found in step 2
[18:47:56.483] INFO: Collecting data for 5 seconds...
[18:48:01.499] INFO: Done with hot pixel readout
[18:48:13.621] INFO: PixTest:: pg_setup set to default.



[18:48:13.622] INFO: 13 hot pixels found in step 3
[18:48:14.617] INFO: Collecting data for 5 seconds...
[18:48:19.635] INFO: Done with hot pixel readout
[18:48:31.777] INFO: PixTest:: pg_setup set to default.



[18:48:31.778] INFO: 25 hot pixels found in step 4
[18:48:32.772] INFO: Collecting data for 5 seconds...
[18:48:37.789] INFO: Done with hot pixel readout
[18:48:49.934] INFO: PixTest:: pg_setup set to default.




[18:48:49.935] INFO: 17 hot pixels found in step 5
[18:48:50.930] INFO: Collecting data for 5 seconds...
[18:48:55.946] INFO: Done with hot pixel readout
[18:49:08.095] INFO: PixTest:: pg_setup set to default.



[18:49:08.096] INFO: 17 hot pixels found in step 6
[18:49:09.093] INFO: Collecting data for 5 seconds...
[18:49:14.110] INFO: Done with hot pixel readout
[18:49:26.268] INFO: PixTest:: pg_setup set to default.



[18:49:26.269] INFO: 16 hot pixels found in step 7
[18:49:27.265] INFO: Collecting data for 5 seconds...
[18:49:32.282] INFO: Done with hot pixel readout
[18:49:44.399] INFO: PixTest:: pg_setup set to default.





[18:49:44.400] INFO: 24 hot pixels found in step 8
[18:49:45.396] INFO: Collecting data for 5 seconds...
[18:49:50.413] INFO: Done with hot pixel readout
[18:50:02.475] INFO: PixTest:: pg_setup set to default.



[18:50:02.475] INFO: 16 hot pixels found in step 9
[18:50:03.471] INFO: Collecting data for 5 seconds...
[18:50:08.488] INFO: Done with hot pixel readout
[18:50:20.522] INFO: PixTest:: pg_setup set to default.




[18:50:20.523] INFO: 20 hot pixels found in step 10
[18:50:21.518] INFO: Collecting data for 5 seconds...
[18:50:26.535] INFO: Done with hot pixel readout
[18:50:38.655] INFO: PixTest:: pg_setup set to default.





[18:50:38.656] INFO: 21 hot pixels found in step 11
[18:50:39.651] INFO: Collecting data for 5 seconds...
[18:50:44.668] INFO: Done with hot pixel readout
[18:50:56.799] INFO: PixTest:: pg_setup set to default.

[18:50:56.800] INFO: 16 hot pixels found in step 12
[18:50:57.795] INFO: Collecting data for 5 seconds...
[18:51:02.812] INFO: Done with hot pixel readout
[18:51:14.965] INFO: PixTest:: pg_setup set to default.




[18:51:14.966] INFO: 18 hot pixels found in step 13
[18:51:15.961] INFO: Collecting data for 5 seconds...
[18:51:20.978] INFO: Done with hot pixel readout
[18:51:33.112] INFO: PixTest:: pg_setup set to default.



[18:51:33.113] INFO: 21 hot pixels found in step 14
[18:51:33.152] INFO: 21 hot pixels could not be trimmed and have been masked.
[18:51:33.156] INFO: PixTest::trimHotPixels() done
[18:51:33.156] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C0.dat
[18:51:33.162] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C1.dat
[18:51:33.168] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C2.dat
[18:51:33.174] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C3.dat
[18:51:33.179] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C4.dat
[18:51:33.184] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C5.dat
[18:51:33.189] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C6.dat
[18:51:33.194] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C7.dat
[18:51:33.200] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C8.dat
[18:51:33.205] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C9.dat
[18:51:33.210] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C10.dat
[18:51:33.215] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C11.dat
[18:51:33.221] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C12.dat
[18:51:33.226] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C13.dat
[18:51:33.231] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C14.dat
[18:51:33.236] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C15.dat
[18:51:33.242] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//defaultMaskFile.dat
[18:51:33.252] INFO: enter test to run
[18:53:33.082] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[18:53:33.082] INFO: running: highrate
[18:53:33.087] INFO: ----------------------------------------------------------------------
[18:53:33.087] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[18:53:33.087] INFO: ----------------------------------------------------------------------
[18:53:33.087] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[18:53:33.087] INFO: edge/corner pixel THR is adjusted
[18:53:33.087] INFO: PixTestHighRate::trimHotPixels: step 0...
[18:53:34.045] INFO: Collecting data for 1 seconds...
[18:53:35.048] INFO: Done with hot pixel readout
[18:53:39.100] INFO: PixTest:: pg_setup set to default.
[18:53:39.101] INFO: 0 hot pixels found in step 0
[18:53:39.107] INFO: 0 hot pixels could not be trimmed and have been masked.
[18:53:39.197] INFO: PixTest::trimHotPixels() done
[18:53:39.197] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C0.dat
[18:53:39.207] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C1.dat
[18:53:39.213] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C2.dat
[18:53:39.218] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C3.dat
[18:53:39.223] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C4.dat
[18:53:39.229] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C5.dat
[18:53:39.234] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C6.dat
[18:53:39.239] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C7.dat
[18:53:39.244] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C8.dat
[18:53:39.249] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C9.dat
[18:53:39.255] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C10.dat
[18:53:39.260] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C11.dat
[18:53:39.265] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C12.dat
[18:53:39.271] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C13.dat
[18:53:39.276] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C14.dat
[18:53:39.281] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//trimParameters35_C15.dat
[18:53:39.286] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-3-41_FPIXTest-17C-FNAL-160919-1337-150V_2016-09-19_13h37m_1474310246/000_FPIXTest_p17//defaultMaskFile.dat
[18:53:39.303] INFO: enter test to run
[18:54:06.410] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[18:54:06.410] INFO: running: xray
[18:54:06.411] INFO: ----------------------------------------------------------------------
[18:54:06.412] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[18:54:06.412] INFO: ----------------------------------------------------------------------
[18:54:06.415] INFO: ROC 0 masking hot pixel 1/5
[18:54:06.415] INFO: ROC 0 masking hot pixel 1/37
[18:54:06.415] INFO: ROC 0 masking hot pixel 5/34
[18:54:06.415] INFO: ROC 0 masking hot pixel 11/30
[18:54:06.415] INFO: ROC 0 masking hot pixel 12/32
[18:54:06.415] INFO: ROC 0 masking hot pixel 26/20
[18:54:06.415] INFO: ROC 0 masking hot pixel 30/17
[18:54:06.415] INFO: ROC 1 masking hot pixel 3/24
[18:54:06.415] INFO: ROC 1 masking hot pixel 9/36
[18:54:06.415] INFO: ROC 3 masking hot pixel 13/15
[18:54:06.415] INFO: ROC 4 masking hot pixel 4/15
[18:54:06.415] INFO: ROC 5 masking hot pixel 2/26
[18:54:06.415] INFO: ROC 5 masking hot pixel 12/20
[18:54:06.415] INFO: ROC 5 masking hot pixel 13/55
[18:54:06.415] INFO: ROC 5 masking hot pixel 23/15
[18:54:06.415] INFO: ROC 5 masking hot pixel 23/20
[18:54:06.415] INFO: ROC 5 masking hot pixel 27/13
[18:54:06.415] INFO: ROC 5 masking hot pixel 33/17
[18:54:06.415] INFO: ROC 5 masking hot pixel 47/17
[18:54:06.415] INFO: ROC 6 masking hot pixel 1/30
[18:54:06.415] INFO: ROC 6 masking hot pixel 13/17
[18:54:06.415] INFO: ROC 6 masking hot pixel 14/57
[18:54:06.416] INFO: ROC 0 masking pixel 1/5
[18:54:06.416] INFO: ROC 0 masking pixel 1/37
[18:54:06.416] INFO: ROC 0 masking pixel 5/34
[18:54:06.416] INFO: ROC 0 masking pixel 11/30
[18:54:06.416] INFO: ROC 0 masking pixel 12/32
[18:54:06.416] INFO: ROC 0 masking pixel 26/20
[18:54:06.416] INFO: ROC 0 masking pixel 30/17
[18:54:06.416] INFO: ROC 1 masking pixel 3/24
[18:54:06.416] INFO: ROC 1 masking pixel 9/36
[18:54:06.416] INFO: ROC 3 masking pixel 13/15
[18:54:06.416] INFO: ROC 4 masking pixel 4/15
[18:54:06.416] INFO: ROC 5 masking pixel 2/26
[18:54:06.416] INFO: ROC 5 masking pixel 12/20
[18:54:06.416] INFO: ROC 5 masking pixel 13/55
[18:54:06.416] INFO: ROC 5 masking pixel 23/15
[18:54:06.416] INFO: ROC 5 masking pixel 23/20
[18:54:06.416] INFO: ROC 5 masking pixel 27/13
[18:54:06.416] INFO: ROC 5 masking pixel 33/17
[18:54:06.416] INFO: ROC 5 masking pixel 47/17
[18:54:06.416] INFO: ROC 6 masking pixel 1/30
[18:54:06.416] INFO: ROC 6 masking pixel 13/17
[18:54:06.416] INFO: ROC 6 masking pixel 14/57
[18:54:07.394] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[18:54:18.505] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[18:54:48.503] INFO: Resuming triggers.
[18:54:59.618] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[18:55:29.488] INFO: Resuming triggers.
[18:55:40.605] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[18:56:10.451] INFO: Resuming triggers.
[18:56:21.564] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[18:56:51.286] INFO: Resuming triggers.
[18:57:02.399] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[18:57:32.261] INFO: Resuming triggers.
[18:57:43.378] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[18:58:13.691] INFO: Resuming triggers.
[18:58:24.806] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[18:58:55.305] INFO: Resuming triggers.
[18:59:06.418] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[18:59:36.441] INFO: Resuming triggers.
[18:59:47.561] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[19:00:16.962] INFO: Resuming triggers.
[19:00:17.305] INFO: data taking finished, elapsed time: 100 seconds.
[19:00:18.664] INFO: PixTest:: pg_setup set to default.
[19:00:18.667] INFO: PixTestXray::doPhRun() done
[19:00:18.861] INFO: enter test to run
[19:01:38.535] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[19:01:38.535] INFO: running: xray
[19:01:38.536] INFO: ----------------------------------------------------------------------
[19:01:38.536] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[19:01:38.536] INFO: ----------------------------------------------------------------------
[19:01:38.548] INFO: ROC 0 masking hot pixel 1/5
[19:01:38.548] INFO: ROC 0 masking hot pixel 1/37
[19:01:38.548] INFO: ROC 0 masking hot pixel 5/34
[19:01:38.548] INFO: ROC 0 masking hot pixel 11/30
[19:01:38.548] INFO: ROC 0 masking hot pixel 12/32
[19:01:38.548] INFO: ROC 0 masking hot pixel 26/20
[19:01:38.548] INFO: ROC 0 masking hot pixel 30/17
[19:01:38.548] INFO: ROC 1 masking hot pixel 3/24
[19:01:38.548] INFO: ROC 1 masking hot pixel 9/36
[19:01:38.548] INFO: ROC 3 masking hot pixel 13/15
[19:01:38.548] INFO: ROC 4 masking hot pixel 4/15
[19:01:38.548] INFO: ROC 5 masking hot pixel 2/26
[19:01:38.548] INFO: ROC 5 masking hot pixel 12/20
[19:01:38.548] INFO: ROC 5 masking hot pixel 13/55
[19:01:38.548] INFO: ROC 5 masking hot pixel 23/15
[19:01:38.548] INFO: ROC 5 masking hot pixel 23/20
[19:01:38.548] INFO: ROC 5 masking hot pixel 27/13
[19:01:38.548] INFO: ROC 5 masking hot pixel 33/17
[19:01:38.548] INFO: ROC 5 masking hot pixel 47/17
[19:01:38.548] INFO: ROC 6 masking hot pixel 1/30
[19:01:38.548] INFO: ROC 6 masking hot pixel 13/17
[19:01:38.548] INFO: ROC 6 masking hot pixel 14/57
[19:01:38.548] INFO: ROC 0 masking pixel 1/5
[19:01:38.548] INFO: ROC 0 masking pixel 1/37
[19:01:38.548] INFO: ROC 0 masking pixel 5/34
[19:01:38.548] INFO: ROC 0 masking pixel 11/30
[19:01:38.548] INFO: ROC 0 masking pixel 12/32
[19:01:38.548] INFO: ROC 0 masking pixel 26/20
[19:01:38.548] INFO: ROC 0 masking pixel 30/17
[19:01:38.548] INFO: ROC 1 masking pixel 3/24
[19:01:38.548] INFO: ROC 1 masking pixel 9/36
[19:01:38.548] INFO: ROC 3 masking pixel 13/15
[19:01:38.548] INFO: ROC 4 masking pixel 4/15
[19:01:38.548] INFO: ROC 5 masking pixel 2/26
[19:01:38.548] INFO: ROC 5 masking pixel 12/20
[19:01:38.548] INFO: ROC 5 masking pixel 13/55
[19:01:38.548] INFO: ROC 5 masking pixel 23/15
[19:01:38.548] INFO: ROC 5 masking pixel 23/20
[19:01:38.548] INFO: ROC 5 masking pixel 27/13
[19:01:38.548] INFO: ROC 5 masking pixel 33/17
[19:01:38.548] INFO: ROC 5 masking pixel 47/17
[19:01:38.548] INFO: ROC 6 masking pixel 1/30
[19:01:38.548] INFO: ROC 6 masking pixel 13/17
[19:01:38.548] INFO: ROC 6 masking pixel 14/57
[19:01:39.515] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[19:01:46.182] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[19:02:16.285] INFO: Resuming triggers.
[19:02:22.954] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[19:02:53.144] INFO: Resuming triggers.
[19:02:59.812] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[19:03:29.921] INFO: Resuming triggers.
[19:03:36.587] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[19:04:06.734] INFO: Resuming triggers.
[19:04:13.401] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[19:04:43.706] INFO: Resuming triggers.
[19:04:50.377] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[19:05:20.547] INFO: Resuming triggers.
[19:05:27.216] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[19:05:57.418] INFO: Resuming triggers.
[19:06:04.084] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[19:06:34.250] INFO: Resuming triggers.
[19:06:40.914] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[19:07:11.063] INFO: Resuming triggers.
[19:07:17.727] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[19:07:48.936] INFO: Resuming triggers.
[19:07:55.601] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[19:08:26.416] INFO: Resuming triggers.
[19:08:33.078] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[19:09:03.896] INFO: Resuming triggers.
[19:09:10.562] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[19:09:41.794] INFO: Resuming triggers.
[19:09:48.459] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[19:10:19.377] INFO: Resuming triggers.
[19:10:26.038] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[19:10:57.076] INFO: Resuming triggers.
[19:10:57.435] INFO: data taking finished, elapsed time: 100 seconds.
[19:10:59.498] INFO: PixTest:: pg_setup set to default.
[19:10:59.501] INFO: PixTestXray::doPhRun() done
[19:10:59.658] INFO: enter test to run
[19:11:34.787] INFO: test: HighRate no parameter change
[19:11:34.787] INFO: running: highrate
[19:11:34.810] INFO: ----------------------------------------------------------------------
[19:11:34.810] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[19:11:34.810] INFO: ----------------------------------------------------------------------
[19:11:34.955] INFO: Expecting 768 events.
[19:11:36.102] INFO: 768 events read in total (424ms).
[19:11:36.103] INFO: Test took 1281ms.
[19:11:36.107] INFO: ROC 0 masking pixel 1/5
[19:11:36.107] INFO: ROC 0 masking pixel 1/37
[19:11:36.107] INFO: ROC 0 masking pixel 5/34
[19:11:36.107] INFO: ROC 0 masking pixel 11/30
[19:11:36.107] INFO: ROC 0 masking pixel 12/32
[19:11:36.107] INFO: ROC 0 masking pixel 26/20
[19:11:36.107] INFO: ROC 0 masking pixel 30/17
[19:11:36.107] INFO: ROC 1 masking pixel 3/24
[19:11:36.107] INFO: ROC 1 masking pixel 9/36
[19:11:36.107] INFO: ROC 3 masking pixel 13/15
[19:11:36.107] INFO: ROC 4 masking pixel 4/15
[19:11:36.107] INFO: ROC 5 masking pixel 2/26
[19:11:36.107] INFO: ROC 5 masking pixel 12/20
[19:11:36.107] INFO: ROC 5 masking pixel 13/55
[19:11:36.108] INFO: ROC 5 masking pixel 23/15
[19:11:36.108] INFO: ROC 5 masking pixel 23/20
[19:11:36.108] INFO: ROC 5 masking pixel 27/13
[19:11:36.108] INFO: ROC 5 masking pixel 33/17
[19:11:36.108] INFO: ROC 5 masking pixel 47/17
[19:11:36.108] INFO: ROC 6 masking pixel 1/30
[19:11:36.108] INFO: ROC 6 masking pixel 13/17
[19:11:36.108] INFO: ROC 6 masking pixel 14/57
[19:11:36.905] INFO: Expecting 41600 events.
[19:11:40.008] INFO: 41600 events read in total (2576ms).
[19:11:40.009] INFO: Test took 3898ms.
[19:11:40.046] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:11:40.768] INFO: Expecting 41600 events.
[19:11:43.969] INFO: 41600 events read in total (2674ms).
[19:11:43.970] INFO: Test took 3905ms.
[19:11:44.007] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:11:44.726] INFO: Expecting 41600 events.
[19:11:47.990] INFO: 41600 events read in total (2737ms).
[19:11:47.991] INFO: Test took 3965ms.
[19:11:48.028] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:11:48.745] INFO: Expecting 41600 events.
[19:11:52.020] INFO: 41600 events read in total (2748ms).
[19:11:52.021] INFO: Test took 3974ms.
[19:11:52.059] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:11:52.772] INFO: Expecting 41600 events.
[19:11:56.043] INFO: 41600 events read in total (2744ms).
[19:11:56.044] INFO: Test took 3965ms.
[19:11:56.081] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:11:56.799] INFO: Expecting 41600 events.
[19:12:00.074] INFO: 41600 events read in total (2748ms).
[19:12:00.075] INFO: Test took 3974ms.
[19:12:00.112] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:00.827] INFO: Expecting 41600 events.
[19:12:04.107] INFO: 41600 events read in total (2753ms).
[19:12:04.108] INFO: Test took 3976ms.
[19:12:04.146] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:04.863] INFO: Expecting 41600 events.
[19:12:08.137] INFO: 41600 events read in total (2747ms).
[19:12:08.138] INFO: Test took 3974ms.
[19:12:08.175] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:08.890] INFO: Expecting 41600 events.
[19:12:12.149] INFO: 41600 events read in total (2732ms).
[19:12:12.150] INFO: Test took 3956ms.
[19:12:12.187] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:12.906] INFO: Expecting 41600 events.
[19:12:16.181] INFO: 41600 events read in total (2749ms).
[19:12:16.182] INFO: Test took 3975ms.
[19:12:16.220] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:16.938] INFO: Expecting 41600 events.
[19:12:20.214] INFO: 41600 events read in total (2749ms).
[19:12:20.215] INFO: Test took 3976ms.
[19:12:20.252] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:20.973] INFO: Expecting 41600 events.
[19:12:24.243] INFO: 41600 events read in total (2743ms).
[19:12:24.244] INFO: Test took 3973ms.
[19:12:24.282] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:24.998] INFO: Expecting 41600 events.
[19:12:28.273] INFO: 41600 events read in total (2748ms).
[19:12:28.274] INFO: Test took 3972ms.
[19:12:28.311] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:29.026] INFO: Expecting 41600 events.
[19:12:32.305] INFO: 41600 events read in total (2752ms).
[19:12:32.307] INFO: Test took 3977ms.
[19:12:32.351] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:33.059] INFO: Expecting 41600 events.
[19:12:36.348] INFO: 41600 events read in total (2762ms).
[19:12:36.349] INFO: Test took 3980ms.
[19:12:36.387] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:37.101] INFO: Expecting 41600 events.
[19:12:40.372] INFO: 41600 events read in total (2744ms).
[19:12:40.373] INFO: Test took 3966ms.
[19:12:40.410] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:41.128] INFO: Expecting 41600 events.
[19:12:44.390] INFO: 41600 events read in total (2735ms).
[19:12:44.391] INFO: Test took 3961ms.
[19:12:44.428] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:45.146] INFO: Expecting 41600 events.
[19:12:48.425] INFO: 41600 events read in total (2752ms).
[19:12:48.426] INFO: Test took 3977ms.
[19:12:48.463] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:49.183] INFO: Expecting 41600 events.
[19:12:52.446] INFO: 41600 events read in total (2736ms).
[19:12:52.447] INFO: Test took 3963ms.
[19:12:52.485] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:53.206] INFO: Expecting 41600 events.
[19:12:56.363] INFO: 41600 events read in total (2630ms).
[19:12:56.364] INFO: Test took 3859ms.
[19:12:56.401] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:12:56.758] INFO: enter test to run
[19:13:56.354] INFO: test: HighRate no parameter change
[19:13:56.354] INFO: running: highrate
[19:13:56.355] INFO: ----------------------------------------------------------------------
[19:13:56.355] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[19:13:56.355] INFO: ----------------------------------------------------------------------
[19:13:56.366] INFO: ROC 0 masking pixel 1/5
[19:13:56.366] INFO: ROC 0 masking pixel 1/37
[19:13:56.366] INFO: ROC 0 masking pixel 5/34
[19:13:56.366] INFO: ROC 0 masking pixel 11/30
[19:13:56.366] INFO: ROC 0 masking pixel 12/32
[19:13:56.366] INFO: ROC 0 masking pixel 26/20
[19:13:56.366] INFO: ROC 0 masking pixel 30/17
[19:13:56.366] INFO: ROC 1 masking pixel 3/24
[19:13:56.366] INFO: ROC 1 masking pixel 9/36
[19:13:56.366] INFO: ROC 3 masking pixel 13/15
[19:13:56.366] INFO: ROC 4 masking pixel 4/15
[19:13:56.366] INFO: ROC 5 masking pixel 2/26
[19:13:56.366] INFO: ROC 5 masking pixel 12/20
[19:13:56.366] INFO: ROC 5 masking pixel 13/55
[19:13:56.366] INFO: ROC 5 masking pixel 23/15
[19:13:56.366] INFO: ROC 5 masking pixel 23/20
[19:13:56.366] INFO: ROC 5 masking pixel 27/13
[19:13:56.366] INFO: ROC 5 masking pixel 33/17
[19:13:56.366] INFO: ROC 5 masking pixel 47/17
[19:13:56.366] INFO: ROC 6 masking pixel 1/30
[19:13:56.366] INFO: ROC 6 masking pixel 13/17
[19:13:56.366] INFO: ROC 6 masking pixel 14/57
[19:13:56.972] INFO: Expecting 208000 events.
[19:14:09.026] INFO: 208000 events read in total (11527ms).
[19:14:09.029] INFO: Test took 12662ms.
[19:14:09.193] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:14:09.452] INFO: number of dead pixels (per ROC): 8 2 2 1 1 8 5 1 0 0 0 0 0 0 0 0
[19:14:09.452] INFO: number of red-efficiency pixels: 100 57 78 156 185 176 192 95 117 143 121 125 101 85 38 32
[19:14:09.452] INFO: number of X-ray hits detected: 74215 49962 76552 120419 125905 133103 130456 89074 86730 113259 107548 93634 96852 57646 24567 29032
[19:14:09.452] 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:14:09.452] INFO: number of Vcal hits detected: 207504 207842 207824 207791 207761 207428 207556 207854 207878 207854 207877 207870 207896 207910 207961 207968
[19:14:09.452] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[19:14:09.452] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.9 99.9 99.9 99.7 99.8 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[19:14:09.452] INFO: X-ray hit rate [MHz/cm2]: 21.8 14.6 22.4 35.3 36.9 39.0 38.2 26.1 25.4 33.2 31.5 27.4 28.4 16.9 7.2 8.5
[19:14:09.452] INFO: PixTestHighRate::doXPixelAlive() done
[19:14:09.504] INFO: PixTest:: pg_setup set to default.
[19:14:09.519] INFO: enter test to run
[19:15:13.906] INFO: test: HighRate no parameter change
[19:15:13.906] INFO: running: highrate
[19:15:13.907] INFO: ----------------------------------------------------------------------
[19:15:13.907] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[19:15:13.907] INFO: ----------------------------------------------------------------------
[19:15:13.917] INFO: ROC 0 masking pixel 1/5
[19:15:13.917] INFO: ROC 0 masking pixel 1/37
[19:15:13.918] INFO: ROC 0 masking pixel 5/34
[19:15:13.918] INFO: ROC 0 masking pixel 11/30
[19:15:13.918] INFO: ROC 0 masking pixel 12/32
[19:15:13.918] INFO: ROC 0 masking pixel 26/20
[19:15:13.918] INFO: ROC 0 masking pixel 30/17
[19:15:13.918] INFO: ROC 1 masking pixel 3/24
[19:15:13.918] INFO: ROC 1 masking pixel 9/36
[19:15:13.918] INFO: ROC 3 masking pixel 13/15
[19:15:13.918] INFO: ROC 4 masking pixel 4/15
[19:15:13.918] INFO: ROC 5 masking pixel 2/26
[19:15:13.918] INFO: ROC 5 masking pixel 12/20
[19:15:13.918] INFO: ROC 5 masking pixel 13/55
[19:15:13.918] INFO: ROC 5 masking pixel 23/15
[19:15:13.918] INFO: ROC 5 masking pixel 23/20
[19:15:13.918] INFO: ROC 5 masking pixel 27/13
[19:15:13.918] INFO: ROC 5 masking pixel 33/17
[19:15:13.918] INFO: ROC 5 masking pixel 47/17
[19:15:13.918] INFO: ROC 6 masking pixel 1/30
[19:15:13.918] INFO: ROC 6 masking pixel 13/17
[19:15:13.918] INFO: ROC 6 masking pixel 14/57
[19:15:14.527] INFO: Expecting 208000 events.
[19:15:28.358] INFO: 208000 events read in total (13304ms).
[19:15:28.364] INFO: Test took 14446ms.
[19:15:28.676] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:15:28.989] INFO: number of dead pixels (per ROC): 8 2 2 1 1 8 5 1 0 0 0 0 0 0 0 0
[19:15:28.989] INFO: number of red-efficiency pixels: 282 124 252 440 631 529 605 319 309 464 352 277 376 225 76 68
[19:15:28.989] INFO: number of X-ray hits detected: 147202 98132 151908 236897 247539 263812 258333 176901 171801 223552 213707 185264 190909 115424 49487 57803
[19:15:28.989] 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:15:28.989] INFO: number of Vcal hits detected: 207306 207773 207633 207478 207241 207029 207084 207611 207672 207502 207619 207704 207592 207757 207921 207931
[19:15:28.989] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.7 99.8 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[19:15:28.989] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.8 99.7 99.6 99.5 99.6 99.8 99.8 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[19:15:28.989] INFO: X-ray hit rate [MHz/cm2]: 43.1 28.8 44.5 69.4 72.6 77.3 75.7 51.9 50.4 65.5 62.6 54.3 56.0 33.8 14.5 16.9
[19:15:28.989] INFO: PixTestHighRate::doXPixelAlive() done
[19:15:29.039] INFO: PixTest:: pg_setup set to default.
[19:15:29.056] INFO: enter test to run
[19:16:16.457] INFO: test: HighRate no parameter change
[19:16:16.457] INFO: running: highrate
[19:16:16.458] INFO: ----------------------------------------------------------------------
[19:16:16.458] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[19:16:16.458] INFO: ----------------------------------------------------------------------
[19:16:16.469] INFO: ROC 0 masking pixel 1/5
[19:16:16.469] INFO: ROC 0 masking pixel 1/37
[19:16:16.469] INFO: ROC 0 masking pixel 5/34
[19:16:16.469] INFO: ROC 0 masking pixel 11/30
[19:16:16.469] INFO: ROC 0 masking pixel 12/32
[19:16:16.469] INFO: ROC 0 masking pixel 26/20
[19:16:16.469] INFO: ROC 0 masking pixel 30/17
[19:16:16.469] INFO: ROC 1 masking pixel 3/24
[19:16:16.469] INFO: ROC 1 masking pixel 9/36
[19:16:16.469] INFO: ROC 3 masking pixel 13/15
[19:16:16.469] INFO: ROC 4 masking pixel 4/15
[19:16:16.469] INFO: ROC 5 masking pixel 2/26
[19:16:16.469] INFO: ROC 5 masking pixel 12/20
[19:16:16.469] INFO: ROC 5 masking pixel 13/55
[19:16:16.469] INFO: ROC 5 masking pixel 23/15
[19:16:16.469] INFO: ROC 5 masking pixel 23/20
[19:16:16.469] INFO: ROC 5 masking pixel 27/13
[19:16:16.469] INFO: ROC 5 masking pixel 33/17
[19:16:16.469] INFO: ROC 5 masking pixel 47/17
[19:16:16.469] INFO: ROC 6 masking pixel 1/30
[19:16:16.469] INFO: ROC 6 masking pixel 13/17
[19:16:16.469] INFO: ROC 6 masking pixel 14/57
[19:16:17.074] INFO: Expecting 208000 events.
[19:16:32.696] INFO: 208000 events read in total (15095ms).
[19:16:32.703] INFO: Test took 16233ms.
[19:16:33.165] INFO: Fetched DAQ statistics. Counters are being reset now.
[19:16:33.518] INFO: number of dead pixels (per ROC): 8 2 2 1 1 8 5 1 0 0 0 0 0 0 0 0
[19:16:33.518] INFO: number of red-efficiency pixels: 489 219 509 837 1368 1039 1322 644 581 1013 720 617 692 452 115 109
[19:16:33.519] INFO: number of X-ray hits detected: 209684 139708 216811 336711 355388 375254 367304 252090 244406 318900 302566 263002 272378 164535 69694 81999
[19:16:33.519] 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:16:33.519] INFO: number of Vcal hits detected: 207022 207662 207272 206941 206161 206291 206012 207174 207348 206783 207162 207298 207192 207470 207883 207887
[19:16:33.519] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.6 99.2 99.4 99.3 99.7 99.7 99.5 99.6 99.7 99.6 99.8 99.9 99.9
[19:16:33.519] INFO: Vcal hit overall efficiency (%): 99.5 99.8 99.7 99.5 99.1 99.2 99.0 99.6 99.7 99.4 99.6 99.7 99.6 99.7 99.9 99.9
[19:16:33.519] INFO: X-ray hit rate [MHz/cm2]: 61.5 40.9 63.5 98.7 104.2 110.0 107.7 73.9 71.6 93.5 88.7 77.1 79.8 48.2 20.4 24.0
[19:16:33.519] INFO: PixTestHighRate::doXPixelAlive() done
[19:16:33.567] INFO: PixTest:: pg_setup set to default.
[19:16:33.583] INFO: enter test to run
[19:17:23.073] INFO: test: exit no parameter change
[19:17:23.529] QUIET: Connection to board 33 closed.
[19:17:23.545] INFO: pXar: this is the end, my friend