[14:44:14.173] INFO: *** Welcome to pxar ***
[14:44:14.173] INFO: *** Today: 2016/09/27
[14:44:15.032] INFO: *** Version: v1.9.0-818-g96727
[14:44:15.032] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//dacParameters35_C15.dat
[14:44:15.056] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:44:15.056] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//defaultMaskFile.dat
[14:44:15.056] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C15.dat
[14:44:15.160] INFO: clk: 4
[14:44:15.160] INFO: ctr: 4
[14:44:15.160] INFO: sda: 19
[14:44:15.160] INFO: tin: 9
[14:44:15.160] INFO: level: 15
[14:44:15.160] INFO: triggerdelay: 0
[14:44:15.160] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[14:44:15.160] INFO: Log level: INFO
[14:44:15.176] QUIET: Connection to board DTB_WREKRL opened.
[14:44:15.179] 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:
------------------------------------------------------
[14:44:15.182] INFO: RPC call hashes of host and DTB match: 398089610
[14:44:16.722] INFO: DUT info:
[14:44:16.722] INFO: The DUT currently contains the following objects:
[14:44:16.722] INFO: 2 TBM Cores tbm08c (2 ON)
[14:44:16.722] INFO: TBM Core alpha (0): 7 registers set
[14:44:16.722] INFO: TBM Core beta (1): 7 registers set
[14:44:16.722] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:44:16.722] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:16.722] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:16.722] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:16.723] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:16.723] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:16.723] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:16.723] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:16.723] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:16.723] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:16.723] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:16.723] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:16.723] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:16.723] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:16.723] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:16.723] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:16.723] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:44:17.126] INFO: enter 'restricted' command line mode
[14:44:17.126] INFO: enter test to run
[14:44:31.475] INFO: test: PixelAlive no parameter change
[14:44:31.475] INFO: running: pixelalive
[14:44:31.481] INFO: ----------------------------------------------------------------------
[14:44:31.481] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:44:31.481] INFO: ----------------------------------------------------------------------
[14:44:31.798] INFO: Expecting 41600 events.
[14:44:36.100] INFO: 41600 events read in total (3584ms).
[14:44:36.266] INFO: Test took 4783ms.
[14:44:36.277] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:36.540] INFO: PixTestAlive::aliveTest() done
[14:44:36.540] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 2 0 0
[14:44:36.588] INFO: enter test to run
[14:44:49.971] INFO: test: timing no parameter change
[14:44:49.971] INFO: running: timing
[14:44:49.974] INFO: ######################################################################
[14:44:49.974] INFO: PixTestTiming::doTest()
[14:44:49.974] INFO: ######################################################################
[14:44:49.975] INFO: ----------------------------------------------------------------------
[14:44:49.975] INFO: PixTestTiming::TBMPhaseScan()
[14:44:49.975] INFO: ----------------------------------------------------------------------
[14:49:23.086] INFO: TBM Phase Settings: 248
[14:49:23.086] INFO: 400MHz Phase: 6
[14:49:23.086] INFO: 160MHz Phase: 7
[14:49:23.086] INFO: Functional Phase Area: 5
[14:49:23.089] INFO: Test took 273115 ms.
[14:49:23.089] INFO: PixTestTiming::TBMPhaseScan() done.
[14:49:23.089] INFO: ----------------------------------------------------------------------
[14:49:23.089] INFO: PixTestTiming::ROCDelayScan()
[14:49:23.089] INFO: ----------------------------------------------------------------------
[14:52:11.000] INFO: ROC Delay Settings: 228
[14:52:11.000] INFO: ROC Header-Trailer/Token Delay: 11
[14:52:11.000] INFO: ROC Port 0 Delay: 4
[14:52:11.000] INFO: ROC Port 1 Delay: 4
[14:52:11.000] INFO: Functional ROC Area: 6
[14:52:11.003] INFO: Test took 168914 ms.
[14:52:11.003] INFO: PixTestTiming::ROCDelayScan() done.
[14:52:11.003] INFO: ----------------------------------------------------------------------
[14:52:11.003] INFO: PixTestTiming::TimingTest()
[14:52:11.003] INFO: ----------------------------------------------------------------------
[14:52:27.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:42.460] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:57.328] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:12.071] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:26.974] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:41.909] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:56.806] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:11.754] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:26.695] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:41.658] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:42.036] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:42.053] INFO: ----------------------------------------------------------------------
[14:54:42.053] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[14:54:42.053] INFO: ----------------------------------------------------------------------
[14:54:42.053] INFO: ----------------------------------------------------------------------
[14:54:42.053] INFO: Read back bit status: 1
[14:54:42.053] INFO: ----------------------------------------------------------------------
[14:54:42.054] INFO: ----------------------------------------------------------------------
[14:54:42.054] INFO: Timings are good!
[14:54:42.054] INFO: ----------------------------------------------------------------------
[14:54:42.054] INFO: Test took 150051 ms.
[14:54:42.054] INFO: PixTestTiming::TimingTest() done.
[14:54:42.054] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//tbmParameters_C0a.dat
[14:54:42.054] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:54:42.054] INFO: PixTestTiming::doTest took 592083 ms.
[14:54:42.054] INFO: PixTestTiming::doTest() done
[14:54:42.054] INFO: Write out TBMPhaseScan_0_V0
[14:54:42.054] INFO: Write out TBMPhaseScan_1_V0
[14:54:42.054] INFO: Write out CombinedTBMPhaseScan_V0
[14:54:42.083] INFO: Write out ROCDelayScan3_V0
[14:54:42.084] INFO: enter test to run
[14:55:13.607] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:55:13.607] INFO: running: highrate
[14:55:13.607] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:55:14.021] INFO: ----------------------------------------------------------------------
[14:55:14.021] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:55:14.021] INFO: ----------------------------------------------------------------------
[14:55:14.021] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:55:14.022] INFO: edge/corner pixel THR is adjusted
[14:55:14.022] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:55:14.002] INFO: Collecting data for 5 seconds...
[14:55:20.022] INFO: Done with hot pixel readout
[14:55:31.742] INFO: PixTest:: pg_setup set to default.
[14:55:31.743] INFO: 4 hot pixels found in step 0
[14:55:32.735] INFO: Collecting data for 5 seconds...
[14:55:37.752] INFO: Done with hot pixel readout
[14:55:49.344] INFO: PixTest:: pg_setup set to default.
[14:55:49.345] INFO: 2 hot pixels found in step 1
[14:55:50.337] INFO: Collecting data for 5 seconds...
[14:55:55.356] INFO: Done with hot pixel readout
[14:56:06.905] INFO: PixTest:: pg_setup set to default.
[14:56:06.906] INFO: 2 hot pixels found in step 2
[14:56:07.898] INFO: Collecting data for 5 seconds...
[14:56:12.915] INFO: Done with hot pixel readout
[14:56:24.058] INFO: PixTest:: pg_setup set to default.
[14:56:24.058] INFO: 5 hot pixels found in step 3
[14:56:25.051] INFO: Collecting data for 5 seconds...
[14:56:30.068] INFO: Done with hot pixel readout
[14:56:40.974] INFO: PixTest:: pg_setup set to default.
[14:56:40.975] INFO: 0 hot pixels found in step 4
[14:56:41.011] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:56:41.015] INFO: PixTest::trimHotPixels() done
[14:56:41.028] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C0.dat
[14:56:41.035] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C1.dat
[14:56:41.041] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C2.dat
[14:56:41.047] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C3.dat
[14:56:41.053] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C4.dat
[14:56:41.059] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C5.dat
[14:56:41.064] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C6.dat
[14:56:41.070] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C7.dat
[14:56:41.076] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C8.dat
[14:56:41.081] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C9.dat
[14:56:41.087] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C10.dat
[14:56:41.092] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C11.dat
[14:56:41.098] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C12.dat
[14:56:41.104] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C13.dat
[14:56:41.109] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C14.dat
[14:56:41.115] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C15.dat
[14:56:41.121] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//defaultMaskFile.dat
[14:56:41.131] INFO: enter test to run
[14:57:01.958] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:57:01.958] INFO: running: highrate
[14:57:01.963] INFO: ----------------------------------------------------------------------
[14:57:01.963] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:57:01.963] INFO: ----------------------------------------------------------------------
[14:57:01.963] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:57:01.963] INFO: edge/corner pixel THR is adjusted
[14:57:01.963] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:57:02.920] INFO: Collecting data for 1 seconds...
[14:57:03.923] INFO: Done with hot pixel readout
[14:57:07.668] INFO: PixTest:: pg_setup set to default.
[14:57:07.669] INFO: 0 hot pixels found in step 0
[14:57:07.675] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:57:07.769] INFO: PixTest::trimHotPixels() done
[14:57:07.770] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C0.dat
[14:57:07.794] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C1.dat
[14:57:07.804] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C2.dat
[14:57:07.809] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C3.dat
[14:57:07.815] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C4.dat
[14:57:07.821] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C5.dat
[14:57:07.826] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C6.dat
[14:57:07.832] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C7.dat
[14:57:07.838] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C8.dat
[14:57:07.843] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C9.dat
[14:57:07.849] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C10.dat
[14:57:07.854] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C11.dat
[14:57:07.860] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C12.dat
[14:57:07.866] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C13.dat
[14:57:07.871] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C14.dat
[14:57:07.877] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//trimParameters35_C15.dat
[14:57:07.882] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-40_FPIXTest-17C-FNAL-160923-1232-150V_2016-09-23_12h32m_1474651943/000_FPIXTest_p17//defaultMaskFile.dat
[14:57:07.891] INFO: enter test to run
[14:57:32.638] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:57:32.638] INFO: running: xray
[14:57:32.639] INFO: ----------------------------------------------------------------------
[14:57:32.639] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:57:32.639] INFO: ----------------------------------------------------------------------
[14:57:33.602] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:57:45.276] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:58:14.072] INFO: Resuming triggers.
[14:58:25.753] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:58:56.306] INFO: Resuming triggers.
[14:59:07.986] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:59:38.132] INFO: Resuming triggers.
[14:59:49.813] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:00:20.256] INFO: Resuming triggers.
[15:00:31.935] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[15:01:01.331] INFO: Resuming triggers.
[15:01:13.012] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:01:42.320] INFO: Resuming triggers.
[15:01:53.997] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[15:02:23.468] INFO: Resuming triggers.
[15:02:35.148] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[15:03:04.149] INFO: Resuming triggers.
[15:03:11.075] INFO: data taking finished, elapsed time: 100 seconds.
[15:03:26.495] INFO: PixTest:: pg_setup set to default.
[15:03:26.498] INFO: PixTestXray::doPhRun() done
[15:03:26.709] INFO: enter test to run
[15:04:18.707] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:04:18.707] INFO: running: xray
[15:04:18.708] INFO: ----------------------------------------------------------------------
[15:04:18.708] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:04:18.708] INFO: ----------------------------------------------------------------------
[15:04:19.673] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:04:26.660] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:04:55.980] INFO: Resuming triggers.
[15:05:02.972] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[15:05:32.963] INFO: Resuming triggers.
[15:05:39.951] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[15:06:10.032] INFO: Resuming triggers.
[15:06:17.021] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[15:06:47.060] INFO: Resuming triggers.
[15:06:54.053] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:07:24.109] INFO: Resuming triggers.
[15:07:31.101] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[15:08:01.138] INFO: Resuming triggers.
[15:08:08.135] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[15:08:38.824] INFO: Resuming triggers.
[15:08:45.813] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[15:09:16.548] INFO: Resuming triggers.
[15:09:23.539] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[15:09:54.571] INFO: Resuming triggers.
[15:10:01.565] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:10:32.474] INFO: Resuming triggers.
[15:10:39.461] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[15:11:10.194] INFO: Resuming triggers.
[15:11:17.186] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[15:11:47.865] INFO: Resuming triggers.
[15:11:54.857] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[15:12:25.615] INFO: Resuming triggers.
[15:12:32.606] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[15:13:04.071] INFO: Resuming triggers.
[15:13:06.547] INFO: data taking finished, elapsed time: 100 seconds.
[15:13:18.124] INFO: PixTest:: pg_setup set to default.
[15:13:18.127] INFO: PixTestXray::doPhRun() done
[15:13:18.280] INFO: enter test to run
[15:14:27.023] INFO: test: HighRate no parameter change
[15:14:27.023] INFO: running: highrate
[15:14:27.041] INFO: ----------------------------------------------------------------------
[15:14:27.042] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:14:27.042] INFO: ----------------------------------------------------------------------
[15:14:27.187] INFO: Expecting 768 events.
[15:14:28.324] INFO: 768 events read in total (421ms).
[15:14:28.325] INFO: Test took 1272ms.
[15:14:29.128] INFO: Expecting 41600 events.
[15:14:32.140] INFO: 41600 events read in total (2486ms).
[15:14:32.141] INFO: Test took 3810ms.
[15:14:32.172] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:32.901] INFO: Expecting 41600 events.
[15:14:36.129] INFO: 41600 events read in total (2701ms).
[15:14:36.130] INFO: Test took 3942ms.
[15:14:36.163] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:36.890] INFO: Expecting 41600 events.
[15:14:40.174] INFO: 41600 events read in total (2757ms).
[15:14:40.175] INFO: Test took 3995ms.
[15:14:40.207] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:40.932] INFO: Expecting 41600 events.
[15:14:44.209] INFO: 41600 events read in total (2750ms).
[15:14:44.210] INFO: Test took 3984ms.
[15:14:44.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:44.956] INFO: Expecting 41600 events.
[15:14:48.263] INFO: 41600 events read in total (2780ms).
[15:14:48.265] INFO: Test took 4002ms.
[15:14:48.309] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:49.021] INFO: Expecting 41600 events.
[15:14:52.326] INFO: 41600 events read in total (2778ms).
[15:14:52.327] INFO: Test took 3998ms.
[15:14:52.364] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:53.066] INFO: Expecting 41600 events.
[15:14:56.356] INFO: 41600 events read in total (2763ms).
[15:14:56.357] INFO: Test took 3969ms.
[15:14:56.390] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:57.101] INFO: Expecting 41600 events.
[15:15:00.563] INFO: 41600 events read in total (2935ms).
[15:15:00.564] INFO: Test took 4156ms.
[15:15:00.597] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:01.321] INFO: Expecting 41600 events.
[15:15:04.547] INFO: 41600 events read in total (2699ms).
[15:15:04.548] INFO: Test took 3932ms.
[15:15:04.581] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:05.309] INFO: Expecting 41600 events.
[15:15:08.533] INFO: 41600 events read in total (2697ms).
[15:15:08.534] INFO: Test took 3936ms.
[15:15:08.567] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:09.297] INFO: Expecting 41600 events.
[15:15:12.513] INFO: 41600 events read in total (2690ms).
[15:15:12.514] INFO: Test took 3929ms.
[15:15:12.546] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:13.270] INFO: Expecting 41600 events.
[15:15:16.484] INFO: 41600 events read in total (2687ms).
[15:15:16.485] INFO: Test took 3922ms.
[15:15:16.517] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:17.241] INFO: Expecting 41600 events.
[15:15:20.467] INFO: 41600 events read in total (2699ms).
[15:15:20.468] INFO: Test took 3934ms.
[15:15:20.502] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:21.232] INFO: Expecting 41600 events.
[15:15:24.452] INFO: 41600 events read in total (2693ms).
[15:15:24.453] INFO: Test took 3933ms.
[15:15:24.486] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:25.209] INFO: Expecting 41600 events.
[15:15:28.417] INFO: 41600 events read in total (2681ms).
[15:15:28.418] INFO: Test took 3916ms.
[15:15:28.450] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:29.180] INFO: Expecting 41600 events.
[15:15:32.396] INFO: 41600 events read in total (2690ms).
[15:15:32.397] INFO: Test took 3930ms.
[15:15:32.428] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:33.154] INFO: Expecting 41600 events.
[15:15:36.370] INFO: 41600 events read in total (2689ms).
[15:15:36.371] INFO: Test took 3926ms.
[15:15:36.403] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:37.130] INFO: Expecting 41600 events.
[15:15:40.353] INFO: 41600 events read in total (2696ms).
[15:15:40.354] INFO: Test took 3933ms.
[15:15:40.386] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:41.112] INFO: Expecting 41600 events.
[15:15:44.312] INFO: 41600 events read in total (2673ms).
[15:15:44.313] INFO: Test took 3909ms.
[15:15:44.345] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:45.071] INFO: Expecting 41600 events.
[15:15:48.175] INFO: 41600 events read in total (2578ms).
[15:15:48.177] INFO: Test took 3814ms.
[15:15:48.209] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:48.575] INFO: enter test to run
[15:16:42.438] INFO: test: HighRate no parameter change
[15:16:42.438] INFO: running: highrate
[15:16:42.439] INFO: ----------------------------------------------------------------------
[15:16:42.439] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:16:42.439] INFO: ----------------------------------------------------------------------
[15:16:43.055] INFO: Expecting 208000 events.
[15:16:54.824] INFO: 208000 events read in total (11242ms).
[15:16:54.827] INFO: Test took 12380ms.
[15:16:54.961] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:16:55.212] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 2 0 0
[15:16:55.212] INFO: number of red-efficiency pixels: 75 49 95 110 133 158 126 92 63 96 113 92 83 67 23 24
[15:16:55.213] INFO: number of X-ray hits detected: 60508 42236 64426 101763 105466 114014 110512 76255 65390 88346 86619 70264 74721 47402 20432 23403
[15:16:55.213] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:16:55.213] INFO: number of Vcal hits detected: 207874 207950 207904 207887 207865 207838 207872 207907 207935 207902 207886 207908 207914 207834 207977 207976
[15:16:55.213] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0
[15:16:55.213] INFO: Vcal hit overall efficiency (%): 99.9 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 99.9 100.0 100.0 99.9 100.0 100.0
[15:16:55.213] INFO: X-ray hit rate [MHz/cm2]: 17.7 12.4 18.9 29.8 30.9 33.4 32.4 22.4 19.2 25.9 25.4 20.6 21.9 13.9 6.0 6.9
[15:16:55.213] INFO: PixTestHighRate::doXPixelAlive() done
[15:16:55.263] INFO: PixTest:: pg_setup set to default.
[15:16:55.277] INFO: enter test to run
[15:18:16.934] INFO: test: HighRate no parameter change
[15:18:16.934] INFO: running: highrate
[15:18:16.935] INFO: ----------------------------------------------------------------------
[15:18:16.935] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:18:16.935] INFO: ----------------------------------------------------------------------
[15:18:17.552] INFO: Expecting 208000 events.
[15:18:30.980] INFO: 208000 events read in total (12902ms).
[15:18:30.985] INFO: Test took 14039ms.
[15:18:31.264] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:31.557] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 2 0 0
[15:18:31.557] INFO: number of red-efficiency pixels: 165 107 276 331 379 445 480 305 180 280 318 256 184 172 57 51
[15:18:31.557] INFO: number of X-ray hits detected: 127534 88318 135559 212930 220504 238761 231015 159692 137811 184956 180788 148177 155943 99721 43068 48718
[15:18:31.557] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:18:31.557] INFO: number of Vcal hits detected: 207781 207891 207708 207645 207600 207523 207466 207679 207816 207710 207667 207733 207811 207719 207943 207948
[15:18:31.557] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[15:18:31.557] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[15:18:31.557] INFO: X-ray hit rate [MHz/cm2]: 37.4 25.9 39.7 62.4 64.6 70.0 67.7 46.8 40.4 54.2 53.0 43.4 45.7 29.2 12.6 14.3
[15:18:31.557] INFO: PixTestHighRate::doXPixelAlive() done
[15:18:31.604] INFO: PixTest:: pg_setup set to default.
[15:18:31.616] INFO: enter test to run
[15:18:47.133] INFO: test: HighRate no parameter change
[15:18:47.133] INFO: running: highrate
[15:18:47.134] INFO: ----------------------------------------------------------------------
[15:18:47.134] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:18:47.134] INFO: ----------------------------------------------------------------------
[15:18:47.746] INFO: Expecting 208000 events.
[15:19:02.905] INFO: 208000 events read in total (14632ms).
[15:19:02.912] INFO: Test took 15768ms.
[15:19:03.322] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:03.661] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 2 0 0
[15:19:03.661] INFO: number of red-efficiency pixels: 317 242 605 753 799 1023 1058 644 366 535 688 547 441 368 86 89
[15:19:03.661] INFO: number of X-ray hits detected: 191603 133885 203318 320792 331291 359755 348276 241391 208376 280148 274227 224095 236542 150661 65134 73254
[15:19:03.661] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:19:03.661] INFO: number of Vcal hits detected: 207599 207740 207213 207114 207079 206758 206662 207204 207607 207408 207198 207371 207524 207491 207911 207909
[15:19:03.661] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.6 99.6 99.5 99.4 99.7 99.8 99.7 99.6 99.7 99.8 99.8 100.0 100.0
[15:19:03.661] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.6 99.6 99.4 99.4 99.6 99.8 99.7 99.6 99.7 99.8 99.8 100.0 100.0
[15:19:03.661] INFO: X-ray hit rate [MHz/cm2]: 56.2 39.2 59.6 94.0 97.1 105.4 102.1 70.8 61.1 82.1 80.4 65.7 69.3 44.2 19.1 21.5
[15:19:03.661] INFO: PixTestHighRate::doXPixelAlive() done
[15:19:03.710] INFO: PixTest:: pg_setup set to default.
[15:19:03.726] INFO: enter test to run
[15:19:13.189] INFO: test: exit no parameter change
[15:19:13.660] QUIET: Connection to board 33 closed.
[15:19:13.694] INFO: pXar: this is the end, my friend