[14:13:20.883] INFO: *** Welcome to pxar ***
[14:13:20.883] INFO: *** Today: 2017/05/03
[14:13:20.901] INFO: *** Version: v1.9.0-825-g6bc29
[14:13:20.901] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-6-06_FPIXTest-17C-FNAL-170421-1021-300V_2017-04-21_10h21m_1492788098/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-6-06_FPIXTest-17C-FNAL-170421-1021-300V_2017-04-21_10h21m_1492788098/000_FPIXTest_p17//dacParameters35_C15.dat
[14:13:20.902] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-6-06_FPIXTest-17C-FNAL-170421-1021-300V_2017-04-21_10h21m_1492788098/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-6-06_FPIXTest-17C-FNAL-170421-1021-300V_2017-04-21_10h21m_1492788098/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:13:20.902] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-6-06_FPIXTest-17C-FNAL-170421-1021-300V_2017-04-21_10h21m_1492788098/000_FPIXTest_p17//defaultMaskFile.dat
[14:13:20.902] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-6-06_FPIXTest-17C-FNAL-170421-1021-300V_2017-04-21_10h21m_1492788098/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-6-06_FPIXTest-17C-FNAL-170421-1021-300V_2017-04-21_10h21m_1492788098/000_FPIXTest_p17//trimParameters35_C15.dat
[14:13:20.970] INFO: clk: 4
[14:13:20.970] INFO: ctr: 4
[14:13:20.970] INFO: sda: 19
[14:13:20.970] INFO: tin: 9
[14:13:20.970] INFO: level: 15
[14:13:20.970] INFO: triggerdelay: 0
[14:13:20.970] QUIET: Instanciating API for pxar v1.9.0+825~g6bc290c
[14:13:20.970] INFO: Log level: DEBUG
[14:13:20.984] QUIET: Connection to board DTB_WREKRL opened.
[14:13:20.988] 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:13:20.991] INFO: RPC call hashes of host and DTB match: 398089610
[14:13:22.523] INFO: DUT info:
[14:13:22.523] INFO: The DUT currently contains the following objects:
[14:13:22.523] INFO: 2 TBM Cores tbm08c (2 ON)
[14:13:22.523] INFO: TBM Core alpha (0): 7 registers set
[14:13:22.523] INFO: TBM Core beta (1): 7 registers set
[14:13:22.523] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:13:22.523] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.523] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.523] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.523] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.523] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.523] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.523] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.523] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.523] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.523] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.523] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.523] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.523] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.524] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.524] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.524] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:13:22.524] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:13:22.525] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(0)
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:13:22.526] DEBUG: <PixTestParameters.cc/dump:L107> checkidig: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10000
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> ignorereadback: checkbox(0)
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:13:22.527] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:13:22.528] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:13:22.530] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 29057024
[14:13:22.530] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1c91eb0
[14:13:22.530] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1bbb310
[14:13:22.530] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f063dd94010
[14:13:22.530] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f0643fff510
[14:13:22.530] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 29122560 fPxarMemory = 0x7f063dd94010
[14:13:22.538] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 377mA
[14:13:22.539] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 463.1mA
[14:13:22.539] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: 17.0 C
[14:13:22.539] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:13:22.940] INFO: enter 'restricted' command line mode
[14:13:22.940] INFO: enter test to run
[14:13:27.203] INFO: test: PixelAlive no parameter change
[14:13:27.203] INFO: running: pixelalive
[14:13:27.203] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:13:27.207] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:13:27.207] DEBUG: <PixTestAlive.cc/runCommand:L62> running command: alivetest
[14:13:27.211] INFO: ----------------------------------------------------------------------
[14:13:27.211] 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:13:27.211] INFO: ----------------------------------------------------------------------
[14:13:27.215] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:13:27.534] INFO: Expecting 41600 events.
[14:13:31.876] INFO: 41600 events read in total (3624ms).
[14:13:32.045] INFO: Test took 4830ms.
[14:13:32.057] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:32.057] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66281
[14:13:32.057] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:13:32.323] INFO: PixTestAlive::aliveTest() done
[14:13:32.323] INFO: number of dead pixels (per ROC): 0 1 0 0 14 69 104 90 1 0 0 0 0 0 0 0
[14:13:32.323] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 0 1 0 2 19 127 431 270 1 3 1 0 0 0 0 0
[14:13:32.323] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:13:32.353] INFO: enter test to run
[14:13:45.227] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:13:45.227] DEBUG: <PixTestParameters.cc/setTestParameter:L119> PixTestParameters: ->Xray<-
[14:13:45.227] DEBUG: <PixTestParameters.cc/setTestParameter:L124> setting source to new value DCLowRate
[14:13:45.227] INFO: running: xray
[14:13:45.227] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:13:45.227] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:13:45.227] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-6-06_FPIXTest-17C-FNAL-170421-1021-300V_2017-04-21_10h21m_1492788098/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-6-06_FPIXTest-17C-FNAL-170421-1021-300V_2017-04-21_10h21m_1492788098/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:13:45.386] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[14:13:45.386] INFO: ----------------------------------------------------------------------
[14:13:45.386] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:13:45.386] INFO: ----------------------------------------------------------------------
[14:13:46.355] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:13:56.799] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[14:13:56.803] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:14:22.430] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1028990 events.
[14:14:26.610] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1028990, pixels seen in all events: 7903240
[14:14:26.639] INFO: Resuming triggers.
[14:14:37.077] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[14:14:37.081] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:15:02.771] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1028231 events.
[14:15:06.855] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1028231, pixels seen in all events: 7912552
[14:15:06.946] INFO: Resuming triggers.
[14:15:17.382] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[14:15:17.386] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:15:42.825] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1028165 events.
[14:15:46.918] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1028165, pixels seen in all events: 7913061
[14:15:47.009] INFO: Resuming triggers.
[14:15:57.451] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[14:15:57.454] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:16:22.957] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1028681 events.
[14:16:27.102] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1028681, pixels seen in all events: 7906806
[14:16:27.195] INFO: Resuming triggers.
[14:16:37.631] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[14:16:37.634] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:17:03.218] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1028049 events.
[14:17:07.327] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1028049, pixels seen in all events: 7914899
[14:17:07.413] INFO: Resuming triggers.
[14:17:17.843] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[14:17:17.846] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:17:43.439] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1027546 events.
[14:17:47.581] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1027546, pixels seen in all events: 7920536
[14:17:47.667] INFO: Resuming triggers.
[14:17:58.094] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[14:17:58.097] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:18:23.618] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1027158 events.
[14:18:27.740] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1027158, pixels seen in all events: 7925950
[14:18:27.827] INFO: Resuming triggers.
[14:18:38.253] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[14:18:38.257] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:19:03.844] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1027119 events.
[14:19:08.112] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1027119, pixels seen in all events: 7926216
[14:19:08.201] INFO: Resuming triggers.
[14:19:18.633] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[14:19:18.636] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:19:44.322] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1027742 events.
[14:19:48.898] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1027742, pixels seen in all events: 7918668
[14:19:48.987] INFO: Resuming triggers.
[14:19:55.396] INFO: data taking finished, elapsed time: 100 seconds.
[14:19:55.592] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:20:10.407] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 631409 events.
[14:20:13.153] DEBUG: <PixTestXray.cc/processData:L823> # events read: 631409, pixels seen in all events: 4871313
[14:20:13.205] INFO: PixTest:: pg_setup set to default.
[14:20:13.208] INFO: PixTestXray::doPhRun() done
[14:20:13.208] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:20:13.349] INFO: enter test to run
[14:20:43.896] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:20:43.896] DEBUG: <PixTestParameters.cc/setTestParameter:L119> PixTestParameters: ->Xray<-
[14:20:43.896] DEBUG: <PixTestParameters.cc/setTestParameter:L124> setting source to new value DCHighRate
[14:20:43.896] INFO: running: xray
[14:20:43.896] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:20:43.896] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:20:43.897] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[14:20:43.897] INFO: ----------------------------------------------------------------------
[14:20:43.897] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:20:43.897] INFO: ----------------------------------------------------------------------
[14:20:44.860] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:20:51.264] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:20:51.267] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:21:14.901] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 630929 events.
[14:21:22.112] DEBUG: <PixTestXray.cc/processData:L823> # events read: 630929, pixels seen in all events: 12681740
[14:21:22.165] INFO: Resuming triggers.
[14:21:28.583] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[14:21:28.586] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:21:52.129] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 632278 events.
[14:21:59.262] DEBUG: <PixTestXray.cc/processData:L823> # events read: 632278, pixels seen in all events: 12665724
[14:21:59.317] INFO: Resuming triggers.
[14:22:05.731] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[14:22:05.735] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:22:30.093] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 631895 events.
[14:22:37.268] DEBUG: <PixTestXray.cc/processData:L823> # events read: 631895, pixels seen in all events: 12669025
[14:22:37.323] INFO: Resuming triggers.
[14:22:43.730] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[14:22:43.733] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:23:07.583] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 631213 events.
[14:23:14.693] DEBUG: <PixTestXray.cc/processData:L823> # events read: 631213, pixels seen in all events: 12677500
[14:23:14.747] INFO: Resuming triggers.
[14:23:21.165] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[14:23:21.168] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:23:45.311] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 632277 events.
[14:23:52.204] DEBUG: <PixTestXray.cc/processData:L823> # events read: 632277, pixels seen in all events: 12665013
[14:23:52.258] INFO: Resuming triggers.
[14:23:58.679] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[14:23:58.682] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:24:22.089] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 632510 events.
[14:24:28.681] DEBUG: <PixTestXray.cc/processData:L823> # events read: 632510, pixels seen in all events: 12662965
[14:24:28.736] INFO: Resuming triggers.
[14:24:35.152] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[14:24:35.155] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:24:58.787] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 631996 events.
[14:25:05.305] DEBUG: <PixTestXray.cc/processData:L823> # events read: 631996, pixels seen in all events: 12667983
[14:25:05.359] INFO: Resuming triggers.
[14:25:11.779] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[14:25:11.782] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:25:35.433] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 632446 events.
[14:25:42.134] DEBUG: <PixTestXray.cc/processData:L823> # events read: 632446, pixels seen in all events: 12663737
[14:25:42.190] INFO: Resuming triggers.
[14:25:48.606] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[14:25:48.609] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:26:12.283] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 632073 events.
[14:26:18.817] DEBUG: <PixTestXray.cc/processData:L823> # events read: 632073, pixels seen in all events: 12667797
[14:26:18.869] INFO: Resuming triggers.
[14:26:25.282] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[14:26:25.285] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:26:49.004] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 631775 events.
[14:26:55.605] DEBUG: <PixTestXray.cc/processData:L823> # events read: 631775, pixels seen in all events: 12670652
[14:26:55.657] INFO: Resuming triggers.
[14:27:02.073] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[14:27:02.077] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:27:25.796] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 632080 events.
[14:27:32.402] DEBUG: <PixTestXray.cc/processData:L823> # events read: 632080, pixels seen in all events: 12667697
[14:27:32.456] INFO: Resuming triggers.
[14:27:38.865] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[14:27:38.869] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:28:02.576] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 631412 events.
[14:28:09.209] DEBUG: <PixTestXray.cc/processData:L823> # events read: 631412, pixels seen in all events: 12676150
[14:28:09.262] INFO: Resuming triggers.
[14:28:15.668] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[14:28:15.672] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:28:39.334] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 631110 events.
[14:28:45.976] DEBUG: <PixTestXray.cc/processData:L823> # events read: 631110, pixels seen in all events: 12679025
[14:28:46.029] INFO: Resuming triggers.
[14:28:52.436] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[14:28:52.439] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:29:16.076] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 631120 events.
[14:29:22.740] DEBUG: <PixTestXray.cc/processData:L823> # events read: 631120, pixels seen in all events: 12679578
[14:29:22.794] INFO: Resuming triggers.
[14:29:29.201] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[14:29:29.204] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:29:52.915] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 631155 events.
[14:29:59.551] DEBUG: <PixTestXray.cc/processData:L823> # events read: 631155, pixels seen in all events: 12678754
[14:29:59.605] INFO: Resuming triggers.
[14:30:03.738] INFO: data taking finished, elapsed time: 100 seconds.
[14:30:03.934] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:30:19.294] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 407168 events.
[14:30:23.580] DEBUG: <PixTestXray.cc/processData:L823> # events read: 407168, pixels seen in all events: 8197065
[14:30:23.605] INFO: PixTest:: pg_setup set to default.
[14:30:23.608] INFO: PixTestXray::doPhRun() done
[14:30:23.608] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:30:23.760] INFO: enter test to run
[14:31:10.633] INFO: test: HighRate no parameter change
[14:31:10.633] INFO: running: highrate
[14:31:10.633] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:31:10.633] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:31:10.633] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:31:10.647] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:31:10.647] INFO: ----------------------------------------------------------------------
[14:31:10.647] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:31:10.647] INFO: ----------------------------------------------------------------------
[14:31:10.791] INFO: Expecting 768 events.
[14:31:11.926] INFO: 768 events read in total (419ms).
[14:31:11.926] INFO: Test took 1269ms.
[14:31:11.932] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:31:12.729] INFO: Expecting 41600 events.
[14:31:16.009] INFO: 41600 events read in total (2753ms).
[14:31:16.010] INFO: Test took 4078ms.
[14:31:16.060] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:16.060] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 479213
[14:31:16.060] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:31:16.061] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:31:16.084] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:31:16.752] INFO: Expecting 41600 events.
[14:31:20.149] INFO: 41600 events read in total (2870ms).
[14:31:20.150] INFO: Test took 4066ms.
[14:31:20.203] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:20.203] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 488517
[14:31:20.203] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:31:20.203] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:31:20.227] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:31:20.883] INFO: Expecting 41600 events.
[14:31:24.293] INFO: 41600 events read in total (2883ms).
[14:31:24.294] INFO: Test took 4067ms.
[14:31:24.344] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:24.344] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 491781
[14:31:24.344] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:31:24.344] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:31:24.369] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:31:25.036] INFO: Expecting 41600 events.
[14:31:28.431] INFO: 41600 events read in total (2868ms).
[14:31:28.433] INFO: Test took 4064ms.
[14:31:28.482] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:28.482] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 488233
[14:31:28.482] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:31:28.482] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:31:28.506] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:31:29.173] INFO: Expecting 41600 events.
[14:31:32.596] INFO: 41600 events read in total (2896ms).
[14:31:32.597] INFO: Test took 4091ms.
[14:31:32.647] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:32.647] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487923
[14:31:32.647] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:31:32.647] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:31:32.671] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:31:33.336] INFO: Expecting 41600 events.
[14:31:36.763] INFO: 41600 events read in total (2900ms).
[14:31:36.764] INFO: Test took 4093ms.
[14:31:36.813] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:36.813] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486421
[14:31:36.813] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:31:36.814] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:31:36.839] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:31:37.506] INFO: Expecting 41600 events.
[14:31:40.926] INFO: 41600 events read in total (2893ms).
[14:31:40.927] INFO: Test took 4088ms.
[14:31:40.977] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:40.977] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 489837
[14:31:40.977] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:31:40.978] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:31:41.002] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:31:41.669] INFO: Expecting 41600 events.
[14:31:45.088] INFO: 41600 events read in total (2892ms).
[14:31:45.089] INFO: Test took 4086ms.
[14:31:45.139] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:45.139] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 485590
[14:31:45.139] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:31:45.139] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:31:45.162] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:31:45.831] INFO: Expecting 41600 events.
[14:31:49.265] INFO: 41600 events read in total (2907ms).
[14:31:49.266] INFO: Test took 4104ms.
[14:31:49.315] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:49.315] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487074
[14:31:49.315] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:31:49.315] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:31:49.340] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:31:50.007] INFO: Expecting 41600 events.
[14:31:53.439] INFO: 41600 events read in total (2905ms).
[14:31:53.440] INFO: Test took 4100ms.
[14:31:53.490] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:53.490] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 488545
[14:31:53.490] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:31:53.490] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:31:53.514] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:31:54.177] INFO: Expecting 41600 events.
[14:31:57.596] INFO: 41600 events read in total (2892ms).
[14:31:57.597] INFO: Test took 4083ms.
[14:31:57.647] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:57.647] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 488591
[14:31:57.647] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:31:57.647] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:31:57.671] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:31:58.341] INFO: Expecting 41600 events.
[14:32:01.817] INFO: 41600 events read in total (2950ms).
[14:32:01.818] INFO: Test took 4147ms.
[14:32:01.869] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:01.869] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 485203
[14:32:01.869] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:32:01.870] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:32:01.893] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:32:02.558] INFO: Expecting 41600 events.
[14:32:05.977] INFO: 41600 events read in total (2893ms).
[14:32:05.979] INFO: Test took 4086ms.
[14:32:06.031] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:06.031] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483907
[14:32:06.031] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:32:06.031] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:32:06.055] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:32:06.711] INFO: Expecting 41600 events.
[14:32:10.208] INFO: 41600 events read in total (2970ms).
[14:32:10.209] INFO: Test took 4154ms.
[14:32:10.260] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:10.261] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486565
[14:32:10.261] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:32:10.261] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:32:10.285] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:32:10.949] INFO: Expecting 41600 events.
[14:32:14.451] INFO: 41600 events read in total (2975ms).
[14:32:14.453] INFO: Test took 4168ms.
[14:32:14.504] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:14.504] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 490776
[14:32:14.504] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:32:14.504] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:32:14.528] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:32:15.193] INFO: Expecting 41600 events.
[14:32:18.511] INFO: 41600 events read in total (2791ms).
[14:32:18.512] INFO: Test took 3984ms.
[14:32:18.563] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:18.563] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486422
[14:32:18.563] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:32:18.563] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:32:18.587] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:32:19.253] INFO: Expecting 41600 events.
[14:32:22.648] INFO: 41600 events read in total (2868ms).
[14:32:22.650] INFO: Test took 4063ms.
[14:32:22.700] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:22.701] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487015
[14:32:22.701] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:32:22.701] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:32:22.727] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:32:23.387] INFO: Expecting 41600 events.
[14:32:26.788] INFO: 41600 events read in total (2874ms).
[14:32:26.789] INFO: Test took 4062ms.
[14:32:26.840] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:26.840] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483096
[14:32:26.840] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:32:26.841] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:32:26.866] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:32:27.527] INFO: Expecting 41600 events.
[14:32:30.965] INFO: 41600 events read in total (2911ms).
[14:32:30.966] INFO: Test took 4100ms.
[14:32:31.018] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:31.018] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487858
[14:32:31.018] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:32:31.018] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:32:31.043] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:32:31.706] INFO: Expecting 41600 events.
[14:32:34.996] INFO: 41600 events read in total (2764ms).
[14:32:34.997] INFO: Test took 3954ms.
[14:32:35.047] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:32:35.047] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 481411
[14:32:35.047] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:32:35.047] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:32:35.346] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 151 eff = 0.95887
[14:32:35.346] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 142 eff = 0.958846
[14:32:35.346] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 140 eff = 0.958822
[14:32:35.346] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 151 eff = 0.958726
[14:32:35.347] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 134 eff = 0.954303
[14:32:35.347] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 168 eff = 0.940865
[14:32:35.347] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 157 eff = 0.843365
[14:32:35.347] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 165 eff = 0.919399
[14:32:35.347] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 139 eff = 0.999519
[14:32:35.347] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 144 eff = 0.999111
[14:32:35.347] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 155 eff = 0.999375
[14:32:35.348] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 153 eff = 0.999688
[14:32:35.348] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 158 eff = 0.999591
[14:32:35.348] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 142 eff = 0.999784
[14:32:35.348] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 147 eff = 0.999928
[14:32:35.348] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 141 eff = 0.999976
[14:32:35.352] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:32:35.373] INFO: enter test to run
[14:38:39.936] INFO: test: HighRate no parameter change
[14:38:39.936] INFO: running: highrate
[14:38:39.937] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:38:39.937] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:38:39.937] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:38:39.938] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:38:39.938] INFO: ----------------------------------------------------------------------
[14:38:39.938] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:38:39.938] INFO: ----------------------------------------------------------------------
[14:38:39.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:38:39.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:38:39.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:38:39.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:38:39.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:38:39.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:38:39.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:38:39.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:38:39.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:38:39.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:38:39.939] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:38:39.939] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:38:39.945] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:38:40.548] INFO: Expecting 208000 events.
[14:38:53.637] INFO: 208000 events read in total (12562ms).
[14:38:53.642] INFO: Test took 13697ms.
[14:38:53.874] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:38:53.874] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2138045
[14:38:53.874] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:38:53.874] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:38:54.153] INFO: number of dead pixels (per ROC): 0 1 0 0 17 69 104 94 1 0 0 0 0 0 0 0
[14:38:54.153] INFO: number of red-efficiency pixels: 1513 1467 1503 1545 1571 1723 3668 2053 90 162 129 112 117 60 21 28
[14:38:54.153] INFO: number of X-ray hits detected: 69992 44891 67780 104817 111368 157557 754062 206717 72940 103617 97864 85536 93657 54059 21850 25064
[14:38:54.153] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:38:54.153] INFO: number of Vcal hits detected: 205777 205784 205772 205724 204751 201958 180799 197443 207861 207778 207830 207887 207879 207937 207979 207972
[14:38:54.153] INFO: Vcal hit fiducial efficiency (%): 98.9 99.0 98.9 98.9 98.8 98.7 89.0 97.8 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[14:38:54.153] INFO: Vcal hit overall efficiency (%): 98.9 98.9 98.9 98.9 98.4 97.1 86.9 94.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[14:38:54.153] INFO: X-ray hit rate [MHz/cm2]: 20.5 13.2 19.9 30.7 32.6 46.2 221.0 60.6 21.4 30.4 28.7 25.1 27.5 15.8 6.4 7.3
[14:38:54.153] INFO: PixTestHighRate::doXPixelAlive() done
[14:38:54.198] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:38:54.198] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:38:54.198] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:38:54.198] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:38:54.198] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:38:54.198] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:38:54.198] INFO: PixTest:: pg_setup set to default.
[14:38:54.198] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:38:54.209] INFO: enter test to run
[14:39:19.048] INFO: test: HighRate no parameter change
[14:39:19.048] INFO: running: highrate
[14:39:19.048] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:39:19.048] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:39:19.048] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:39:19.049] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:39:19.049] INFO: ----------------------------------------------------------------------
[14:39:19.049] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:39:19.050] INFO: ----------------------------------------------------------------------
[14:39:19.050] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:39:19.050] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:39:19.050] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:39:19.050] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:39:19.050] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:39:19.050] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:39:19.050] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:39:19.050] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:39:19.050] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:39:19.050] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:39:19.050] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:39:19.050] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:39:19.057] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:39:19.663] INFO: Expecting 208000 events.
[14:39:34.299] INFO: 208000 events read in total (14109ms).
[14:39:34.306] INFO: Test took 15249ms.
[14:39:34.667] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:34.667] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3298052
[14:39:34.667] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:39:34.667] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:39:34.988] INFO: number of dead pixels (per ROC): 0 1 0 0 18 69 104 93 1 0 0 0 0 0 0 0
[14:39:34.988] INFO: number of red-efficiency pixels: 925 814 947 1084 1171 1328 3542 1691 290 405 351 344 394 242 53 65
[14:39:34.988] INFO: number of X-ray hits detected: 147439 94779 142239 220171 234608 272348 698406 279250 152072 208331 199342 178419 194332 112772 44899 52371
[14:39:34.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
[14:39:34.989] INFO: number of Vcal hits detected: 206768 206906 206767 206575 205543 202707 188214 198629 207642 207473 207585 207629 207578 207746 207943 207934
[14:39:34.989] INFO: Vcal hit fiducial efficiency (%): 99.4 99.5 99.4 99.3 99.3 99.1 92.8 98.3 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[14:39:34.989] INFO: Vcal hit overall efficiency (%): 99.4 99.5 99.4 99.3 98.8 97.5 90.5 95.5 99.8 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[14:39:34.989] INFO: X-ray hit rate [MHz/cm2]: 43.2 27.8 41.7 64.5 68.8 79.8 204.7 81.9 44.6 61.1 58.4 52.3 57.0 33.1 13.2 15.4
[14:39:34.989] INFO: PixTestHighRate::doXPixelAlive() done
[14:39:35.037] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:39:35.037] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:39:35.037] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:39:35.037] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:39:35.037] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:39:35.037] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:39:35.037] INFO: PixTest:: pg_setup set to default.
[14:39:35.037] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:39:35.049] INFO: enter test to run
[14:39:59.944] INFO: test: HighRate no parameter change
[14:39:59.944] INFO: running: highrate
[14:39:59.944] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:39:59.944] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:39:59.944] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:39:59.945] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:39:59.945] INFO: ----------------------------------------------------------------------
[14:39:59.945] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:39:59.945] INFO: ----------------------------------------------------------------------
[14:39:59.945] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:39:59.945] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:39:59.946] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:39:59.946] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:39:59.946] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:39:59.946] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:39:59.946] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:39:59.946] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:39:59.946] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:39:59.946] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:39:59.946] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:39:59.946] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:39:59.953] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:40:00.561] INFO: Expecting 208000 events.
[14:40:16.879] INFO: 208000 events read in total (15791ms).
[14:40:16.887] INFO: Test took 16934ms.
[14:40:17.390] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:17.390] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4457791
[14:40:17.390] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:40:17.390] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:40:17.754] INFO: number of dead pixels (per ROC): 0 1 0 0 18 69 104 93 1 0 0 0 0 0 0 0
[14:40:17.754] INFO: number of red-efficiency pixels: 877 622 924 1373 1608 1583 3543 1709 588 849 755 781 958 472 87 95
[14:40:17.754] INFO: number of X-ray hits detected: 221249 142119 214125 329061 350076 383219 696617 345733 227843 308713 296680 266684 292950 170354 67638 78456
[14:40:17.754] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:40:17.754] INFO: number of Vcal hits detected: 206679 207194 206701 206180 204906 202233 190672 198884 207280 206870 207067 207061 206867 207408 207909 207904
[14:40:17.755] INFO: Vcal hit fiducial efficiency (%): 99.5 99.7 99.4 99.2 99.0 98.9 94.1 98.3 99.7 99.5 99.6 99.6 99.5 99.7 100.0 100.0
[14:40:17.755] INFO: Vcal hit overall efficiency (%): 99.4 99.6 99.4 99.1 98.5 97.2 91.7 95.6 99.7 99.5 99.6 99.5 99.5 99.7 100.0 100.0
[14:40:17.755] INFO: X-ray hit rate [MHz/cm2]: 64.8 41.7 62.8 96.5 102.6 112.3 204.2 101.3 66.8 90.5 87.0 78.2 85.9 49.9 19.8 23.0
[14:40:17.755] INFO: PixTestHighRate::doXPixelAlive() done
[14:40:17.802] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:40:17.802] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:40:17.802] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:40:17.802] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:40:17.802] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:40:17.802] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:40:17.802] INFO: PixTest:: pg_setup set to default.
[14:40:17.802] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:40:17.814] INFO: enter test to run
[14:40:19.824] INFO: test: exit no parameter change
[14:40:19.825] DEBUG: <pXar.cc/main:L340> Final Analog Current: 380.2mA
[14:40:19.826] DEBUG: <pXar.cc/main:L341> Final Digital Current: 463.9mA
[14:40:19.826] DEBUG: <pXar.cc/main:L342> Final Module Temperature: 16.7 C
[14:40:19.826] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
[14:40:20.179] QUIET: Connection to board 33 closed.
[14:40:20.180] INFO: pXar: this is the end, my friend
[14:40:20.180] DEBUG: <PixSetup.cc/~PixSetup:L68> PixSetup free fPxarMemory