Test Date: 1973-11-29 16:33
Analysis date: 2016-10-05 14:49
Logfile
hrData_40.log
[16:17:45.675] INFO: *** Welcome to pxar ***
[16:17:45.675] INFO: *** Today: 2016/10/04
[16:17:45.698] INFO: *** Version: v1.9.0-818-g96727
[16:17:45.699] INFO: readRocDacs: data/mq518/dacParameters35_C0.dat .. data/mq518/dacParameters35_C15.dat
[16:17:45.700] INFO: readTbmDacs: data/mq518/tbmParameters_C0a.dat .. data/mq518/tbmParameters_C0b.dat
[16:17:45.700] INFO: readMaskFile: data/mq518/defaultMaskFile.dat
[16:17:45.700] INFO: readTrimFile: data/mq518/trimParameters35_C0.dat .. data/mq518/trimParameters35_C15.dat
[16:17:45.849] INFO: clk: 4
[16:17:45.849] INFO: ctr: 4
[16:17:45.849] INFO: sda: 19
[16:17:45.849] INFO: tin: 9
[16:17:45.849] INFO: level: 15
[16:17:45.849] INFO: triggerdelay: 0
[16:17:45.849] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:17:45.849] INFO: Log level: DEBUG
[16:17:45.860] QUIET: Connection to board DTB_WRPRHI opened.
[16:17:45.863] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:17:45.866] INFO: RPC call hashes of host and DTB match: 398089610
[16:17:47.468] INFO: DUT info:
[16:17:47.468] INFO: The DUT currently contains the following objects:
[16:17:47.468] INFO: 2 TBM Cores tbm08c (2 ON)
[16:17:47.468] INFO: TBM Core alpha (0): 7 registers set
[16:17:47.468] INFO: TBM Core beta (1): 7 registers set
[16:17:47.468] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:17:47.468] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.468] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.468] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.468] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.468] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.468] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.468] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.468] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.468] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.468] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.468] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.468] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.468] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.468] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.468] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.468] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:17:47.469] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:17:47.470] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:17:47.471] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:17:47.474] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33439744
[16:17:47.474] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x141c0e0
[16:17:47.474] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x11be370
[16:17:47.474] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f2cfdd94010
[16:17:47.474] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f2d03fff510
[16:17:47.474] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33447936 fPxarMemory = 0x7f2cfdd94010
[16:17:47.475] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 380.2mA
[16:17:47.476] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[16:17:47.476] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:17:47.972] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x180af10
[16:17:48.034] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:17:48.034] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:17:48.034] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:17:48.040] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:17:48.040] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:17:48.040] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:17:48.049] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:17:48.049] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:17:48.058] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:17:48.058] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:17:48.074] INFO: PixTestCmd::init()
[16:17:48.083] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:17:48.083] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:17:48.083] INFO: readGainPedestalParameters data/mq518/phCalibrationFitErr35_C0.dat .. data/mq518/phCalibrationFitErr35_C15.dat
[16:17:48.329] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:17:48.330] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:17:48.344] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:17:48.351] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:17:48.351] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:17:48.351] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:17:48.371] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:17:48.371] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:17:48.371] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:17:48.371] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:17:48.371] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:17:48.377] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:17:48.378] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:17:48.399] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:17:48.399] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:17:48.399] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:17:48.399] INFO: readReadbackCal: data/mq518/readbackCal_C0.dat .. data/mq518/readbackCal_C15.dat
[16:17:48.408] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:17:48.417] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:17:48.417] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:17:48.417] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:17:48.417] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:17:48.437] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:17:48.437] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:17:52.939] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[16:17:53.788] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[16:17:53.788] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[16:17:53.788] INFO: ----------------------------------------------------------------------
[16:17:53.788] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[16:17:53.788] INFO: ----------------------------------------------------------------------
[16:17:54.761] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[16:18:07.438] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[16:18:07.441] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:18:34.638] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1249019 events.
[16:18:40.466] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1249019, pixels seen in all events: 5263965
[16:18:40.565] INFO: Resuming triggers.
[16:18:53.243] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[16:18:53.280] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:19:20.396] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1248993 events.
[16:19:26.247] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1248993, pixels seen in all events: 5263586
[16:19:26.409] INFO: Resuming triggers.
[16:19:39.092] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[16:19:39.128] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:20:06.284] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1249484 events.
[16:20:12.042] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1249484, pixels seen in all events: 5257678
[16:20:12.277] INFO: Resuming triggers.
[16:20:24.961] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[16:20:25.001] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:20:52.234] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1249608 events.
[16:20:58.060] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1249608, pixels seen in all events: 5255569
[16:20:58.156] INFO: Resuming triggers.
[16:21:07.509] INFO: data taking finished, elapsed time: 60 seconds.
[16:21:07.705] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:21:27.719] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 921400 events.
[16:21:31.964] DEBUG: <PixTestXray.cc/processData:L823> # events read: 921400, pixels seen in all events: 3867116
[16:21:32.049] INFO: PixTest:: pg_setup set to default.
[16:21:32.136] INFO: PixTestXray::doPhRun() done
[16:21:48.372] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:21:48.372] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[16:21:48.373] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.1mA
[16:21:48.373] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[16:21:48.373] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:21:48.373] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:21:48.373] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:21:48.373] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:21:48.373] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:21:48.374] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:21:48.374] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:21:48.374] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:21:48.374] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:21:48.374] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:21:48.374] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:21:48.374] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:21:48.374] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:21:48.374] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:21:48.374] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:21:48.374] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:21:48.374] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:21:48.374] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:21:48.646] QUIET: Connection to board 58 closed.
[16:21:48.726] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[16:21:53.571] INFO: *** Welcome to pxar ***
[16:21:53.571] INFO: *** Today: 2016/10/04
[16:21:53.599] INFO: *** Version: v1.9.0-818-g96727
[16:21:53.599] INFO: readRocDacs: data/mq518/dacParameters35_C0.dat .. data/mq518/dacParameters35_C15.dat
[16:21:53.600] INFO: readTbmDacs: data/mq518/tbmParameters_C0a.dat .. data/mq518/tbmParameters_C0b.dat
[16:21:53.600] INFO: readMaskFile: data/mq518/defaultMaskFile.dat
[16:21:53.600] INFO: readTrimFile: data/mq518/trimParameters35_C0.dat .. data/mq518/trimParameters35_C15.dat
[16:21:53.752] INFO: clk: 4
[16:21:53.752] INFO: ctr: 4
[16:21:53.752] INFO: sda: 19
[16:21:53.752] INFO: tin: 9
[16:21:53.752] INFO: level: 15
[16:21:53.752] INFO: triggerdelay: 0
[16:21:53.752] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:21:53.752] INFO: Log level: DEBUG
[16:21:53.762] QUIET: Connection to board DTB_WRPRHI opened.
[16:21:53.766] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:21:53.769] INFO: RPC call hashes of host and DTB match: 398089610
[16:21:55.372] INFO: DUT info:
[16:21:55.372] INFO: The DUT currently contains the following objects:
[16:21:55.372] INFO: 2 TBM Cores tbm08c (2 ON)
[16:21:55.372] INFO: TBM Core alpha (0): 7 registers set
[16:21:55.372] INFO: TBM Core beta (1): 7 registers set
[16:21:55.372] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:21:55.372] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.372] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.372] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.373] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.373] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.373] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.373] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.373] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.373] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.373] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.373] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.373] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.373] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.373] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.373] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.373] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:55.373] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:21:55.373] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:21:55.373] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:21:55.373] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:21:55.373] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:21:55.373] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:21:55.373] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:21:55.373] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:21:55.373] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:21:55.373] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:21:55.373] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:21:55.373] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:21:55.373] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:21:55.374] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:21:55.375] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:21:55.376] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:21:55.378] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32739328
[16:21:55.379] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x19340e0
[16:21:55.379] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x16d6370
[16:21:55.379] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f77a5d94010
[16:21:55.379] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f77abfff510
[16:21:55.379] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32747520 fPxarMemory = 0x7f77a5d94010
[16:21:55.380] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381mA
[16:21:55.381] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[16:21:55.381] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[16:21:55.880] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1d23030
[16:21:55.946] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:21:55.946] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:21:55.946] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:21:55.952] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:21:55.952] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:21:55.952] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:21:55.965] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:21:55.965] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:21:55.977] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:21:55.977] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:21:56.000] INFO: PixTestCmd::init()
[16:21:56.015] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:21:56.015] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:21:56.015] INFO: readGainPedestalParameters data/mq518/phCalibrationFitErr35_C0.dat .. data/mq518/phCalibrationFitErr35_C15.dat
[16:21:56.262] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:21:56.262] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:21:56.277] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:21:56.283] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:21:56.284] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:21:56.284] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:21:56.303] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:21:56.303] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:21:56.303] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:21:56.303] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:21:56.303] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:21:56.310] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:21:56.310] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:21:56.324] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:21:56.324] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:21:56.325] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:21:56.325] INFO: readReadbackCal: data/mq518/readbackCal_C0.dat .. data/mq518/readbackCal_C15.dat
[16:21:56.335] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:21:56.343] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:21:56.343] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:21:56.343] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:21:56.343] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:21:56.356] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:21:56.356] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:22:06.844] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[16:22:07.524] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[16:22:07.524] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[16:22:07.524] INFO: ----------------------------------------------------------------------
[16:22:07.524] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[16:22:07.524] INFO: ----------------------------------------------------------------------
[16:22:08.497] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[16:22:16.225] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[16:22:16.228] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:22:41.539] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761380 events.
[16:22:53.441] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761380, pixels seen in all events: 11116708
[16:22:53.690] INFO: Resuming triggers.
[16:23:01.418] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[16:23:01.456] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:23:26.636] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761292 events.
[16:23:38.543] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761292, pixels seen in all events: 11116326
[16:23:38.770] INFO: Resuming triggers.
[16:23:46.499] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[16:23:46.535] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:24:11.702] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761451 events.
[16:24:23.606] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761451, pixels seen in all events: 11114961
[16:24:23.806] INFO: Resuming triggers.
[16:24:31.533] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[16:24:31.599] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:24:56.782] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761243 events.
[16:25:08.739] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761243, pixels seen in all events: 11116802
[16:25:08.973] INFO: Resuming triggers.
[16:25:16.708] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[16:25:16.749] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:25:41.928] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 762055 events.
[16:25:53.874] DEBUG: <PixTestXray.cc/processData:L823> # events read: 762055, pixels seen in all events: 11107154
[16:25:54.106] INFO: Resuming triggers.
[16:26:01.842] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[16:26:01.880] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:26:27.062] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 762025 events.
[16:26:38.958] DEBUG: <PixTestXray.cc/processData:L823> # events read: 762025, pixels seen in all events: 11107757
[16:26:39.188] INFO: Resuming triggers.
[16:26:46.918] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[16:26:46.956] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:27:12.193] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761490 events.
[16:27:24.159] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761490, pixels seen in all events: 11114460
[16:27:24.391] INFO: Resuming triggers.
[16:27:30.362] INFO: data taking finished, elapsed time: 60 seconds.
[16:27:30.558] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:27:50.081] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 588202 events.
[16:27:59.299] DEBUG: <PixTestXray.cc/processData:L823> # events read: 588202, pixels seen in all events: 8570182
[16:27:59.487] INFO: PixTest:: pg_setup set to default.
[16:27:59.529] INFO: PixTestXray::doPhRun() done
[16:28:10.988] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:28:10.989] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[16:28:10.989] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.1mA
[16:28:10.989] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[16:28:10.989] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:28:10.990] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:28:10.990] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:28:10.990] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:28:10.990] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:28:10.990] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:28:10.990] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:28:10.990] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:28:10.990] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:28:10.990] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:28:10.990] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:28:10.990] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:28:10.990] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:28:10.990] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:28:10.990] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:28:10.991] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:28:10.991] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:28:10.991] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:28:11.299] QUIET: Connection to board 58 closed.
[16:28:11.378] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[16:11:21.618] INFO: *** Welcome to pxar ***
[16:11:21.618] INFO: *** Today: 2016/10/04
[16:11:21.642] INFO: *** Version: v1.9.0-818-g96727
[16:11:21.642] INFO: readRocDacs: data/mq518/dacParameters35_C0.dat .. data/mq518/dacParameters35_C15.dat
[16:11:21.643] INFO: readTbmDacs: data/mq518/tbmParameters_C0a.dat .. data/mq518/tbmParameters_C0b.dat
[16:11:21.643] INFO: readMaskFile: data/mq518/defaultMaskFile.dat
[16:11:21.643] INFO: readTrimFile: data/mq518/trimParameters35_C0.dat .. data/mq518/trimParameters35_C15.dat
[16:11:21.792] INFO: clk: 4
[16:11:21.792] INFO: ctr: 4
[16:11:21.792] INFO: sda: 19
[16:11:21.792] INFO: tin: 9
[16:11:21.792] INFO: level: 15
[16:11:21.792] INFO: triggerdelay: 0
[16:11:21.792] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:11:21.792] INFO: Log level: DEBUG
[16:11:21.803] QUIET: Connection to board DTB_WRPRHI opened.
[16:11:21.806] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:11:21.809] INFO: RPC call hashes of host and DTB match: 398089610
[16:11:23.412] INFO: DUT info:
[16:11:23.412] INFO: The DUT currently contains the following objects:
[16:11:23.412] INFO: 2 TBM Cores tbm08c (2 ON)
[16:11:23.412] INFO: TBM Core alpha (0): 7 registers set
[16:11:23.412] INFO: TBM Core beta (1): 7 registers set
[16:11:23.412] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:11:23.412] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.412] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.412] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.412] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.413] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.413] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.413] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.413] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.413] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.413] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.413] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.413] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.413] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.413] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.413] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.413] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:11:23.413] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:11:23.413] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:11:23.413] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:11:23.413] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:11:23.413] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:11:23.413] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:11:23.413] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:11:23.413] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:11:23.413] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:11:23.413] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:11:23.413] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:11:23.413] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:11:23.413] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:11:23.413] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:11:23.413] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:11:23.414] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:11:23.415] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:11:23.416] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:11:23.419] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33050624
[16:11:23.419] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xdda0e0
[16:11:23.419] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xb7c370
[16:11:23.419] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f2909d94010
[16:11:23.419] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f290ffff510
[16:11:23.419] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33058816 fPxarMemory = 0x7f2909d94010
[16:11:23.420] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 380.2mA
[16:11:23.421] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[16:11:23.421] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:11:23.920] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x11c8f00
[16:11:23.987] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:11:23.987] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:11:23.987] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:11:23.993] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:11:23.993] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:11:23.993] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:11:24.004] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:11:24.004] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:11:24.027] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:11:24.027] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:11:24.040] INFO: PixTestCmd::init()
[16:11:24.052] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:11:24.052] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:11:24.052] INFO: readGainPedestalParameters data/mq518/phCalibrationFitErr35_C0.dat .. data/mq518/phCalibrationFitErr35_C15.dat
[16:11:24.301] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:11:24.301] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:11:24.314] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:11:24.320] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:11:24.320] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:11:24.320] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:11:24.341] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:11:24.341] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:11:24.342] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:11:24.342] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:11:24.342] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:11:24.348] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:11:24.348] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:11:24.362] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:11:24.362] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:11:24.362] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:11:24.362] INFO: readReadbackCal: data/mq518/readbackCal_C0.dat .. data/mq518/readbackCal_C15.dat
[16:11:24.372] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:11:24.380] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:11:24.380] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:11:24.380] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:11:24.380] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:11:24.399] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:11:24.399] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:11:32.759] INFO: ######################################################################
[16:11:32.759] INFO: PixTestAlive::doTest()
[16:11:32.759] INFO: ######################################################################
[16:11:32.762] INFO: ----------------------------------------------------------------------
[16:11:32.762] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:11:32.762] INFO: ----------------------------------------------------------------------
[16:11:32.765] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:11:33.144] INFO: Expecting 41600 events.
[16:11:37.565] INFO: 41600 events read in total (3702ms).
[16:11:37.711] INFO: Test took 4946ms.
[16:11:37.723] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:11:37.724] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[16:11:37.724] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:11:37.998] INFO: PixTestAlive::aliveTest() done
[16:11:37.999] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:11:37.999] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 23 8 27 24 35 38 36 39 29 40 40 46 41 30 16 13
[16:11:38.027] INFO: ----------------------------------------------------------------------
[16:11:38.027] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:11:38.027] INFO: ----------------------------------------------------------------------
[16:11:38.029] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:11:38.406] INFO: Expecting 41600 events.
[16:11:41.546] INFO: 41600 events read in total (2425ms).
[16:11:41.546] INFO: Test took 3517ms.
[16:11:41.546] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:11:41.546] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:11:41.546] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:11:41.547] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:11:41.969] INFO: PixTestAlive::maskTest() done
[16:11:41.969] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:11:41.991] INFO: ----------------------------------------------------------------------
[16:11:41.991] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:11:41.991] INFO: ----------------------------------------------------------------------
[16:11:41.993] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:11:42.372] INFO: Expecting 41600 events.
[16:11:46.778] INFO: 41600 events read in total (3691ms).
[16:11:46.779] INFO: Test took 4786ms.
[16:11:46.790] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:11:46.790] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[16:11:46.790] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:11:47.162] INFO: PixTestAlive::addressDecodingTest() done
[16:11:47.162] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:11:47.162] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[16:12:35.716] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:12:35.716] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C3_V0 -> PixelAlive_mod
[16:12:38.395] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:12:38.395] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:12:38.395] INFO: ----------------------------------------------------------------------
[16:12:38.395] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:12:38.396] INFO: ----------------------------------------------------------------------
[16:12:38.535] INFO: Expecting 768 events.
[16:12:39.669] INFO: 768 events read in total (419ms).
[16:12:39.669] INFO: Test took 1268ms.
[16:12:39.675] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:12:40.472] INFO: Expecting 41600 events.
[16:12:43.814] INFO: 41600 events read in total (2815ms).
[16:12:43.818] INFO: Test took 4143ms.
[16:12:43.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:43.897] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 453995
[16:12:43.897] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:12:43.897] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:12:43.934] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:12:44.599] INFO: Expecting 41600 events.
[16:12:48.075] INFO: 41600 events read in total (2949ms).
[16:12:48.078] INFO: Test took 4144ms.
[16:12:48.155] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:48.155] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 462048
[16:12:48.155] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:12:48.155] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:12:48.193] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:12:48.858] INFO: Expecting 41600 events.
[16:12:52.387] INFO: 41600 events read in total (3002ms).
[16:12:52.391] INFO: Test took 4198ms.
[16:12:52.467] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:52.467] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464776
[16:12:52.467] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:12:52.468] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:12:52.506] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:12:53.171] INFO: Expecting 41600 events.
[16:12:56.693] INFO: 41600 events read in total (2995ms).
[16:12:56.697] INFO: Test took 4190ms.
[16:12:56.773] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:56.773] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465583
[16:12:56.773] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:12:56.774] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:12:56.813] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:12:57.478] INFO: Expecting 41600 events.
[16:13:01.003] INFO: 41600 events read in total (2998ms).
[16:13:01.006] INFO: Test took 4193ms.
[16:13:01.082] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:01.082] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 466155
[16:13:01.082] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:13:01.083] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:13:01.122] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:13:01.787] INFO: Expecting 41600 events.
[16:13:05.317] INFO: 41600 events read in total (3003ms).
[16:13:05.321] INFO: Test took 4199ms.
[16:13:05.396] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:05.396] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464835
[16:13:05.396] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:13:05.396] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:13:05.435] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:13:06.100] INFO: Expecting 41600 events.
[16:13:09.584] INFO: 41600 events read in total (2957ms).
[16:13:09.588] INFO: Test took 4153ms.
[16:13:09.663] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:09.663] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465033
[16:13:09.663] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:13:09.663] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:13:09.702] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:13:10.367] INFO: Expecting 41600 events.
[16:13:13.884] INFO: 41600 events read in total (2990ms).
[16:13:13.888] INFO: Test took 4186ms.
[16:13:13.964] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:13.964] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465432
[16:13:13.964] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:13:13.964] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:13:14.003] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:13:14.668] INFO: Expecting 41600 events.
[16:13:18.209] INFO: 41600 events read in total (3014ms).
[16:13:18.213] INFO: Test took 4210ms.
[16:13:18.290] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:18.290] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465127
[16:13:18.290] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:13:18.290] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:13:18.330] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:13:18.995] INFO: Expecting 41600 events.
[16:13:22.543] INFO: 41600 events read in total (3021ms).
[16:13:22.547] INFO: Test took 4217ms.
[16:13:22.624] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:22.624] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465541
[16:13:22.624] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:13:22.624] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:13:22.663] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:13:23.328] INFO: Expecting 41600 events.
[16:13:26.872] INFO: 41600 events read in total (3017ms).
[16:13:26.876] INFO: Test took 4213ms.
[16:13:26.952] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:26.952] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464353
[16:13:26.952] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:13:26.952] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:13:26.991] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:13:27.656] INFO: Expecting 41600 events.
[16:13:31.194] INFO: 41600 events read in total (3011ms).
[16:13:31.197] INFO: Test took 4206ms.
[16:13:31.273] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:31.273] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464488
[16:13:31.273] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:13:31.274] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:13:31.312] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:13:31.978] INFO: Expecting 41600 events.
[16:13:35.526] INFO: 41600 events read in total (3021ms).
[16:13:35.529] INFO: Test took 4216ms.
[16:13:35.606] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:35.606] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465058
[16:13:35.606] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:13:35.606] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:13:35.645] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:13:36.310] INFO: Expecting 41600 events.
[16:13:39.847] INFO: 41600 events read in total (3010ms).
[16:13:39.850] INFO: Test took 4206ms.
[16:13:39.926] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:39.926] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465134
[16:13:39.927] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:13:39.927] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:13:39.966] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:13:40.631] INFO: Expecting 41600 events.
[16:13:44.153] INFO: 41600 events read in total (2995ms).
[16:13:44.156] INFO: Test took 4190ms.
[16:13:44.234] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:44.234] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 463684
[16:13:44.234] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:13:44.234] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:13:44.275] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:13:44.940] INFO: Expecting 41600 events.
[16:13:48.474] INFO: 41600 events read in total (3007ms).
[16:13:48.477] INFO: Test took 4202ms.
[16:13:48.555] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:48.555] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 463773
[16:13:48.555] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:13:48.555] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:13:48.596] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:13:49.262] INFO: Expecting 41600 events.
[16:13:52.787] INFO: 41600 events read in total (2998ms).
[16:13:52.790] INFO: Test took 4194ms.
[16:13:52.872] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:52.872] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464617
[16:13:52.872] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:13:52.873] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:13:52.913] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:13:53.579] INFO: Expecting 41600 events.
[16:13:57.108] INFO: 41600 events read in total (3002ms).
[16:13:57.111] INFO: Test took 4199ms.
[16:13:57.188] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:57.188] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 463980
[16:13:57.188] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:13:57.188] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:13:57.227] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:13:57.893] INFO: Expecting 41600 events.
[16:14:01.413] INFO: 41600 events read in total (2993ms).
[16:14:01.416] INFO: Test took 4189ms.
[16:14:01.493] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:14:01.493] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 463513
[16:14:01.494] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:14:01.494] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:14:01.534] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:14:02.201] INFO: Expecting 41600 events.
[16:14:05.582] INFO: 41600 events read in total (2855ms).
[16:14:05.586] INFO: Test took 4052ms.
[16:14:05.661] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:14:05.661] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 459919
[16:14:05.661] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:14:05.662] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:14:05.921] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 168 eff = 0.999712
[16:14:05.921] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 173 eff = 0.999856
[16:14:05.922] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 159 eff = 0.999303
[16:14:05.922] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 149 eff = 0.999159
[16:14:05.922] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 146 eff = 0.99899
[16:14:05.922] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 131 eff = 0.998966
[16:14:05.922] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 169 eff = 0.999038
[16:14:05.922] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 187 eff = 0.999087
[16:14:05.923] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 156 eff = 0.999159
[16:14:05.923] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 159 eff = 0.998822
[16:14:05.923] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 123 eff = 0.998894
[16:14:05.923] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 148 eff = 0.99887
[16:14:05.923] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 161 eff = 0.999183
[16:14:05.923] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 157 eff = 0.999399
[16:14:05.924] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 148 eff = 0.999663
[16:14:05.924] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 122 eff = 0.999688
[16:14:09.075] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:14:09.075] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:14:09.076] INFO: ----------------------------------------------------------------------
[16:14:09.076] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:14:09.076] INFO: ----------------------------------------------------------------------
[16:14:09.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:14:09.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:14:09.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:14:09.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:14:09.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:14:09.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:14:09.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:14:09.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:14:09.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:14:09.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:14:09.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:14:09.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:14:09.095] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:14:09.764] INFO: Expecting 208000 events.
[16:14:23.259] INFO: 208000 events read in total (12968ms).
[16:14:23.270] INFO: Test took 14175ms.
[16:14:23.622] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:14:23.622] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2044124
[16:14:23.622] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:14:23.623] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:14:23.989] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:14:23.989] INFO: number of red-efficiency pixels: 135 63 134 212 230 282 252 184 227 253 284 269 220 138 76 128
[16:14:23.989] INFO: number of X-ray hits detected: 82547 56247 97342 146422 156525 153747 155088 143373 144730 161030 154128 146848 148520 98169 58799 74050
[16:14:23.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
[16:14:23.989] INFO: number of Vcal hits detected: 207857 207936 207812 207781 207758 207707 207741 207813 207769 207718 207701 207723 207771 207854 207923 207872
[16:14:23.989] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[16:14:23.989] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[16:14:23.989] INFO: X-ray hit rate [MHz/cm2]: 24.2 16.5 28.5 42.9 45.9 45.1 45.5 42.0 42.4 47.2 45.2 43.0 43.5 28.8 17.2 21.7
[16:14:23.989] INFO: PixTestHighRate::doXPixelAlive() done
[16:14:24.034] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:14:24.034] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:14:24.034] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:14:24.034] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:14:24.034] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:14:24.034] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:14:24.034] INFO: PixTest:: pg_setup set to default.
[16:14:29.452] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:14:29.452] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[16:14:31.459] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[16:14:31.459] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[16:14:31.460] INFO: ----------------------------------------------------------------------
[16:14:31.460] INFO: PixTestHighRate::runDaq() running for 2 seconds
[16:14:31.460] INFO: ----------------------------------------------------------------------
[16:14:31.664] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[16:14:31.664] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[16:14:31.664] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[16:14:31.664] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[16:14:31.664] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[16:14:31.664] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[16:14:31.664] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[16:14:31.664] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[16:14:31.664] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[16:14:31.664] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[16:14:31.664] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[16:14:31.664] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[16:14:32.621] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[16:14:34.624] INFO: data taking finished, elapsed time: 2 seconds.
[16:14:36.145] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39820 events with 375858 pixels
[16:14:36.151] INFO: PixTest:: pg_setup set to default.
[16:14:36.291] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[16:14:36.291] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[16:14:36.291] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[16:14:36.292] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[16:14:36.292] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[16:14:36.292] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[16:14:36.292] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[16:14:36.292] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[16:14:36.292] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[16:14:36.292] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[16:14:36.293] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[16:14:36.293] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[16:14:36.293] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[16:14:36.293] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[16:14:36.293] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[16:14:36.293] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[16:14:36.294] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[16:14:36.294] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[16:14:36.294] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[16:14:36.294] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[16:14:36.294] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[16:14:36.294] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[16:14:36.294] INFO: Pixels without X-ray hits (per ROC): 815 1422 827 237 219 220 219 183 225 230 233 342 248 785 1415 918
[16:14:36.294] INFO: PixTestHighRate::doRunDaq() done
[16:14:41.356] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:14:41.356] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C12_V0 -> hitMap_daqbbtest_mod
[16:14:44.308] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:14:44.308] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[16:14:44.309] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.1mA
[16:14:44.309] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:14:44.309] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:14:44.309] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:14:44.309] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:14:44.309] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:14:44.309] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:14:44.309] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:14:44.310] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:14:44.310] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:14:44.310] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:14:44.310] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:14:44.413] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:14:44.413] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:14:44.426] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:14:44.426] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:14:44.426] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:14:44.426] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:14:44.427] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:14:44.427] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:14:44.429] QUIET: Connection to board 58 closed.
[16:14:44.509] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[16:07:24.289] INFO: *** Welcome to pxar ***
[16:07:24.289] INFO: *** Today: 2016/10/04
[16:07:24.314] INFO: *** Version: v1.9.0-818-g96727
[16:07:24.314] INFO: readRocDacs: data/mq518/dacParameters35_C0.dat .. data/mq518/dacParameters35_C15.dat
[16:07:24.315] INFO: readTbmDacs: data/mq518/tbmParameters_C0a.dat .. data/mq518/tbmParameters_C0b.dat
[16:07:24.315] INFO: readMaskFile: data/mq518/defaultMaskFile.dat
[16:07:24.315] INFO: readTrimFile: data/mq518/trimParameters35_C0.dat .. data/mq518/trimParameters35_C15.dat
[16:07:24.465] INFO: clk: 4
[16:07:24.465] INFO: ctr: 4
[16:07:24.465] INFO: sda: 19
[16:07:24.465] INFO: tin: 9
[16:07:24.465] INFO: level: 15
[16:07:24.465] INFO: triggerdelay: 0
[16:07:24.465] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:07:24.465] INFO: Log level: DEBUG
[16:07:24.475] QUIET: Connection to board DTB_WRPRHI opened.
[16:07:24.479] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:07:24.482] INFO: RPC call hashes of host and DTB match: 398089610
[16:07:26.081] INFO: DUT info:
[16:07:26.081] INFO: The DUT currently contains the following objects:
[16:07:26.081] INFO: 2 TBM Cores tbm08c (2 ON)
[16:07:26.081] INFO: TBM Core alpha (0): 7 registers set
[16:07:26.081] INFO: TBM Core beta (1): 7 registers set
[16:07:26.081] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:07:26.081] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.081] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.082] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.082] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.082] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.082] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.082] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.082] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.082] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.082] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.082] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.082] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.082] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.082] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.082] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.082] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:26.082] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:07:26.082] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:07:26.082] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:07:26.082] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:07:26.082] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:07:26.082] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:07:26.082] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:07:26.082] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:07:26.082] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:07:26.082] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:07:26.082] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:07:26.082] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:07:26.083] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:07:26.084] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:07:26.085] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:07:26.088] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31346688
[16:07:26.088] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x12d10e0
[16:07:26.088] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1073370
[16:07:26.088] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7ff46dd94010
[16:07:26.088] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7ff473fff510
[16:07:26.088] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31354880 fPxarMemory = 0x7ff46dd94010
[16:07:26.089] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381mA
[16:07:26.090] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[16:07:26.090] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.7 C
[16:07:26.590] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x16c0090
[16:07:26.653] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:07:26.654] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:07:26.654] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:07:26.659] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:07:26.659] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:07:26.659] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:07:26.672] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:07:26.672] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:07:26.683] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:07:26.683] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:07:26.699] INFO: PixTestCmd::init()
[16:07:26.709] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:07:26.709] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:07:26.709] INFO: readGainPedestalParameters data/mq518/phCalibrationFitErr35_C0.dat .. data/mq518/phCalibrationFitErr35_C15.dat
[16:07:26.959] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:07:26.959] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:07:26.973] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:07:26.979] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:07:26.979] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:07:26.979] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:07:27.001] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:07:27.001] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:07:27.001] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:07:27.001] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:07:27.001] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:07:27.008] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:07:27.008] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:07:27.030] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:07:27.030] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:07:27.030] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:07:27.030] INFO: readReadbackCal: data/mq518/readbackCal_C0.dat .. data/mq518/readbackCal_C15.dat
[16:07:27.040] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:07:27.047] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:07:27.048] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:07:27.048] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:07:27.048] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:07:27.064] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:07:27.064] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:07:35.351] INFO: ######################################################################
[16:07:35.351] INFO: PixTestAlive::doTest()
[16:07:35.351] INFO: ######################################################################
[16:07:35.354] INFO: ----------------------------------------------------------------------
[16:07:35.354] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:07:35.354] INFO: ----------------------------------------------------------------------
[16:07:35.357] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:07:35.735] INFO: Expecting 41600 events.
[16:07:40.116] INFO: 41600 events read in total (3662ms).
[16:07:40.264] INFO: Test took 4907ms.
[16:07:40.276] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:40.276] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[16:07:40.276] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:07:40.550] INFO: PixTestAlive::aliveTest() done
[16:07:40.550] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:07:40.550] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 29 26 29 65 60 49 59 47 53 62 48 49 60 37 18 21
[16:07:40.578] INFO: ----------------------------------------------------------------------
[16:07:40.578] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:07:40.578] INFO: ----------------------------------------------------------------------
[16:07:40.580] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:07:40.957] INFO: Expecting 41600 events.
[16:07:44.054] INFO: 41600 events read in total (2382ms).
[16:07:44.054] INFO: Test took 3474ms.
[16:07:44.054] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:44.055] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:07:44.055] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:07:44.055] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:07:44.478] INFO: PixTestAlive::maskTest() done
[16:07:44.478] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:07:44.496] INFO: ----------------------------------------------------------------------
[16:07:44.496] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:07:44.496] INFO: ----------------------------------------------------------------------
[16:07:44.498] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:07:44.875] INFO: Expecting 41600 events.
[16:07:49.268] INFO: 41600 events read in total (3677ms).
[16:07:49.270] INFO: Test took 4772ms.
[16:07:49.279] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:49.279] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[16:07:49.279] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:07:49.651] INFO: PixTestAlive::addressDecodingTest() done
[16:07:49.651] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:07:49.651] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[16:08:04.779] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:08:04.779] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[16:08:06.779] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:08:06.779] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:08:06.779] INFO: ----------------------------------------------------------------------
[16:08:06.779] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:08:06.779] INFO: ----------------------------------------------------------------------
[16:08:06.918] INFO: Expecting 768 events.
[16:08:08.053] INFO: 768 events read in total (419ms).
[16:08:08.053] INFO: Test took 1268ms.
[16:08:08.059] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:08:08.856] INFO: Expecting 41600 events.
[16:08:12.456] INFO: 41600 events read in total (3073ms).
[16:08:12.460] INFO: Test took 4401ms.
[16:08:12.578] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:12.578] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 667864
[16:08:12.579] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:08:12.579] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:08:12.630] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:08:13.296] INFO: Expecting 41600 events.
[16:08:17.005] INFO: 41600 events read in total (3182ms).
[16:08:17.009] INFO: Test took 4379ms.
[16:08:17.124] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:17.124] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 676582
[16:08:17.124] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:08:17.124] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:08:17.177] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:08:17.843] INFO: Expecting 41600 events.
[16:08:21.605] INFO: 41600 events read in total (3235ms).
[16:08:21.609] INFO: Test took 4432ms.
[16:08:21.725] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:21.725] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 679447
[16:08:21.725] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:08:21.725] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:08:21.778] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:08:22.444] INFO: Expecting 41600 events.
[16:08:26.200] INFO: 41600 events read in total (3229ms).
[16:08:26.205] INFO: Test took 4427ms.
[16:08:26.321] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:26.321] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 680747
[16:08:26.321] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:08:26.322] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:08:26.374] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:08:27.039] INFO: Expecting 41600 events.
[16:08:30.795] INFO: 41600 events read in total (3229ms).
[16:08:30.799] INFO: Test took 4425ms.
[16:08:30.914] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:30.914] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 680654
[16:08:30.914] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:08:30.914] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:08:30.968] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:08:31.634] INFO: Expecting 41600 events.
[16:08:35.400] INFO: 41600 events read in total (3239ms).
[16:08:35.405] INFO: Test took 4437ms.
[16:08:35.519] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:35.520] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 679766
[16:08:35.520] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:08:35.520] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:08:35.574] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:08:36.240] INFO: Expecting 41600 events.
[16:08:40.018] INFO: 41600 events read in total (3251ms).
[16:08:40.023] INFO: Test took 4449ms.
[16:08:40.138] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:40.138] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 680762
[16:08:40.138] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:08:40.138] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:08:40.192] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:08:40.857] INFO: Expecting 41600 events.
[16:08:44.648] INFO: 41600 events read in total (3264ms).
[16:08:44.652] INFO: Test took 4460ms.
[16:08:44.767] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:44.767] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 679082
[16:08:44.767] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:08:44.767] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:08:44.820] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:08:45.486] INFO: Expecting 41600 events.
[16:08:49.281] INFO: 41600 events read in total (3268ms).
[16:08:49.286] INFO: Test took 4466ms.
[16:08:49.400] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:49.400] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 681393
[16:08:49.400] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:08:49.401] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:08:49.453] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:08:50.118] INFO: Expecting 41600 events.
[16:08:53.871] INFO: 41600 events read in total (3226ms).
[16:08:53.875] INFO: Test took 4422ms.
[16:08:53.988] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:53.988] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 678901
[16:08:53.989] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:08:53.989] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:08:54.042] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:08:54.707] INFO: Expecting 41600 events.
[16:08:58.482] INFO: 41600 events read in total (3248ms).
[16:08:58.486] INFO: Test took 4444ms.
[16:08:58.598] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:58.598] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 679349
[16:08:58.598] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:08:58.599] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:08:58.652] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:08:59.317] INFO: Expecting 41600 events.
[16:09:03.032] INFO: 41600 events read in total (3189ms).
[16:09:03.037] INFO: Test took 4385ms.
[16:09:03.152] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:03.152] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 680989
[16:09:03.152] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:09:03.152] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:09:03.206] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:03.871] INFO: Expecting 41600 events.
[16:09:07.654] INFO: 41600 events read in total (3257ms).
[16:09:07.659] INFO: Test took 4453ms.
[16:09:07.774] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:07.774] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 680365
[16:09:07.774] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:09:07.775] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:09:07.827] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:08.492] INFO: Expecting 41600 events.
[16:09:12.278] INFO: 41600 events read in total (3259ms).
[16:09:12.282] INFO: Test took 4455ms.
[16:09:12.399] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:12.399] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 680178
[16:09:12.399] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:09:12.400] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:09:12.454] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:13.120] INFO: Expecting 41600 events.
[16:09:16.905] INFO: 41600 events read in total (3259ms).
[16:09:16.909] INFO: Test took 4455ms.
[16:09:17.024] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:17.024] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 679306
[16:09:17.024] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:09:17.024] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:09:17.078] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:17.744] INFO: Expecting 41600 events.
[16:09:21.527] INFO: 41600 events read in total (3256ms).
[16:09:21.531] INFO: Test took 4453ms.
[16:09:21.646] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:21.646] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 680387
[16:09:21.646] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:09:21.646] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:09:21.701] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:22.366] INFO: Expecting 41600 events.
[16:09:26.153] INFO: 41600 events read in total (3260ms).
[16:09:26.157] INFO: Test took 4456ms.
[16:09:26.273] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:26.273] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 679458
[16:09:26.273] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:09:26.273] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:09:26.327] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:26.993] INFO: Expecting 41600 events.
[16:09:30.787] INFO: 41600 events read in total (3268ms).
[16:09:30.791] INFO: Test took 4464ms.
[16:09:30.905] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:30.905] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 678556
[16:09:30.905] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:09:30.905] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:09:30.959] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:31.624] INFO: Expecting 41600 events.
[16:09:35.402] INFO: 41600 events read in total (3251ms).
[16:09:35.406] INFO: Test took 4447ms.
[16:09:35.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:35.520] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 678354
[16:09:35.520] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:09:35.521] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:09:35.575] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:36.240] INFO: Expecting 41600 events.
[16:09:39.879] INFO: 41600 events read in total (3112ms).
[16:09:39.883] INFO: Test took 4308ms.
[16:09:39.995] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:39.995] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 675202
[16:09:39.995] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:09:39.996] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:09:40.272] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 164 eff = 0.999111
[16:09:40.272] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 157 eff = 0.999543
[16:09:40.272] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 159 eff = 0.998365
[16:09:40.272] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 154 eff = 0.997837
[16:09:40.273] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 153 eff = 0.997837
[16:09:40.273] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 150 eff = 0.997764
[16:09:40.273] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 171 eff = 0.997212
[16:09:40.273] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 180 eff = 0.997981
[16:09:40.274] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 159 eff = 0.998173
[16:09:40.274] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 159 eff = 0.997236
[16:09:40.274] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 146 eff = 0.997452
[16:09:40.274] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 157 eff = 0.998053
[16:09:40.275] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 166 eff = 0.997716
[16:09:40.275] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 155 eff = 0.999087
[16:09:40.275] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 161 eff = 0.999495
[16:09:40.275] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 141 eff = 0.999351
[16:09:52.331] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:09:52.331] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:09:52.331] INFO: ----------------------------------------------------------------------
[16:09:52.331] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:09:52.332] INFO: ----------------------------------------------------------------------
[16:09:52.332] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:09:52.332] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:09:52.332] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:09:52.332] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:09:52.332] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:09:52.332] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:09:52.332] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:09:52.332] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:09:52.332] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:09:52.332] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:09:52.332] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:09:52.332] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:09:52.350] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:53.018] INFO: Expecting 208000 events.
[16:10:08.063] INFO: 208000 events read in total (14518ms).
[16:10:08.079] INFO: Test took 15729ms.
[16:10:08.614] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:08.614] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3104359
[16:10:08.614] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:10:08.615] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:10:09.049] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:10:09.049] INFO: number of red-efficiency pixels: 209 143 292 435 507 448 497 482 427 518 470 477 479 271 134 150
[16:10:09.049] INFO: number of X-ray hits detected: 127373 85778 149572 224749 239935 236653 238355 219221 222310 247190 238182 225498 226800 151824 90304 114056
[16:10:09.049] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:10:09.049] INFO: number of Vcal hits detected: 207787 207849 207641 207520 207459 207513 207448 207486 207543 207375 207474 207477 207451 207713 207858 207846
[16:10:09.049] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.9 99.9 99.9
[16:10:09.049] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.8 99.7 99.8 99.8 99.7 99.7 99.7 99.7 99.9 99.9 99.9
[16:10:09.049] INFO: X-ray hit rate [MHz/cm2]: 37.3 25.1 43.8 65.9 70.3 69.4 69.9 64.3 65.2 72.5 69.8 66.1 66.5 44.5 26.5 33.4
[16:10:09.049] INFO: PixTestHighRate::doXPixelAlive() done
[16:10:09.096] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:10:09.096] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:10:09.096] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:10:09.096] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:10:09.096] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:10:09.096] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:10:09.096] INFO: PixTest:: pg_setup set to default.
[16:10:21.875] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:10:21.875] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[16:10:23.707] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[16:10:23.707] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[16:10:23.708] INFO: ----------------------------------------------------------------------
[16:10:23.708] INFO: PixTestHighRate::runDaq() running for 2 seconds
[16:10:23.708] INFO: ----------------------------------------------------------------------
[16:10:23.904] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[16:10:23.904] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[16:10:23.904] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[16:10:23.904] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[16:10:23.904] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[16:10:23.904] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[16:10:23.904] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[16:10:23.904] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[16:10:23.904] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[16:10:23.904] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[16:10:23.904] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[16:10:23.904] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[16:10:24.861] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[16:10:26.864] INFO: data taking finished, elapsed time: 2 seconds.
[16:10:28.607] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39837 events with 580998 pixels
[16:10:28.612] INFO: PixTest:: pg_setup set to default.
[16:10:28.733] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[16:10:28.734] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[16:10:28.734] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[16:10:28.734] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[16:10:28.734] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[16:10:28.734] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[16:10:28.734] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[16:10:28.735] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[16:10:28.735] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[16:10:28.735] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[16:10:28.735] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[16:10:28.735] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[16:10:28.735] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[16:10:28.735] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[16:10:28.736] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[16:10:28.736] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[16:10:28.736] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[16:10:28.736] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[16:10:28.736] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[16:10:28.736] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[16:10:28.736] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[16:10:28.736] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[16:10:28.736] INFO: Pixels without X-ray hits (per ROC): 445 1070 597 138 138 116 125 110 147 134 118 246 129 579 1015 577
[16:10:28.736] INFO: PixTestHighRate::doRunDaq() done
[16:10:34.923] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:10:34.923] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C12_V0 -> hitMap_daqbbtest_mod
[16:10:42.699] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:10:42.700] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[16:10:42.701] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[16:10:42.701] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[16:10:42.701] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:10:42.701] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:10:42.701] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:10:42.701] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:10:42.701] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:10:42.701] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:10:42.702] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:10:42.702] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:10:42.702] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:10:42.702] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:10:42.810] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:10:42.810] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:10:42.824] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:10:42.824] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:10:42.824] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:10:42.824] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:10:42.824] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:10:42.824] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:10:42.827] QUIET: Connection to board 58 closed.
[16:10:42.906] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[16:04:21.830] INFO: *** Welcome to pxar ***
[16:04:21.830] INFO: *** Today: 2016/10/04
[16:04:21.855] INFO: *** Version: v1.9.0-818-g96727
[16:04:21.855] INFO: readRocDacs: data/mq518/dacParameters35_C0.dat .. data/mq518/dacParameters35_C15.dat
[16:04:21.856] INFO: readTbmDacs: data/mq518/tbmParameters_C0a.dat .. data/mq518/tbmParameters_C0b.dat
[16:04:21.856] INFO: readMaskFile: data/mq518/defaultMaskFile.dat
[16:04:21.856] INFO: readTrimFile: data/mq518/trimParameters35_C0.dat .. data/mq518/trimParameters35_C15.dat
[16:04:22.006] INFO: clk: 4
[16:04:22.006] INFO: ctr: 4
[16:04:22.006] INFO: sda: 19
[16:04:22.006] INFO: tin: 9
[16:04:22.006] INFO: level: 15
[16:04:22.006] INFO: triggerdelay: 0
[16:04:22.006] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:04:22.006] INFO: Log level: DEBUG
[16:04:22.017] QUIET: Connection to board DTB_WRPRHI opened.
[16:04:22.021] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:04:22.023] INFO: RPC call hashes of host and DTB match: 398089610
[16:04:23.626] INFO: DUT info:
[16:04:23.626] INFO: The DUT currently contains the following objects:
[16:04:23.626] INFO: 2 TBM Cores tbm08c (2 ON)
[16:04:23.626] INFO: TBM Core alpha (0): 7 registers set
[16:04:23.626] INFO: TBM Core beta (1): 7 registers set
[16:04:23.626] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:04:23.626] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.626] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.626] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.626] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.626] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.626] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.626] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.626] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.626] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.626] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.626] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.626] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.626] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.626] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.626] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.626] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:04:23.627] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:04:23.628] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:04:23.629] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:04:23.630] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:04:23.632] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32821248
[16:04:23.632] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x162f0e0
[16:04:23.632] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x13d1370
[16:04:23.632] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fa711d94010
[16:04:23.632] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fa717fff510
[16:04:23.632] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32829440 fPxarMemory = 0x7fa711d94010
[16:04:23.633] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381mA
[16:04:23.635] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[16:04:23.635] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[16:04:24.130] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1a1df10
[16:04:24.196] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:04:24.196] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:04:24.196] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:04:24.202] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:04:24.202] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:04:24.202] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:04:24.215] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:04:24.215] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:04:24.234] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:04:24.234] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:04:24.243] INFO: PixTestCmd::init()
[16:04:24.252] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:04:24.253] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:04:24.253] INFO: readGainPedestalParameters data/mq518/phCalibrationFitErr35_C0.dat .. data/mq518/phCalibrationFitErr35_C15.dat
[16:04:24.504] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:04:24.504] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:04:24.525] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:04:24.533] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:04:24.533] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:04:24.533] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:04:24.558] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:04:24.558] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:04:24.558] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:04:24.558] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:04:24.558] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:04:24.565] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:04:24.565] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:04:24.583] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:04:24.583] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:04:24.584] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:04:24.584] INFO: readReadbackCal: data/mq518/readbackCal_C0.dat .. data/mq518/readbackCal_C15.dat
[16:04:24.593] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:04:24.606] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:04:24.606] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:04:24.606] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:04:24.606] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:04:24.619] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:04:24.620] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:04:31.814] INFO: ######################################################################
[16:04:31.815] INFO: PixTestAlive::doTest()
[16:04:31.815] INFO: ######################################################################
[16:04:31.818] INFO: ----------------------------------------------------------------------
[16:04:31.818] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:04:31.818] INFO: ----------------------------------------------------------------------
[16:04:31.820] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:32.198] INFO: Expecting 41600 events.
[16:04:36.538] INFO: 41600 events read in total (3621ms).
[16:04:36.684] INFO: Test took 4863ms.
[16:04:36.694] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:36.694] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[16:04:36.694] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:04:36.971] INFO: PixTestAlive::aliveTest() done
[16:04:36.971] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:04:36.971] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 27 29 51 58 85 80 75 78 89 71 86 75 71 50 29 49
[16:04:36.999] INFO: ----------------------------------------------------------------------
[16:04:36.999] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:04:36.999] INFO: ----------------------------------------------------------------------
[16:04:37.001] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:37.377] INFO: Expecting 41600 events.
[16:04:40.497] INFO: 41600 events read in total (2404ms).
[16:04:40.497] INFO: Test took 3496ms.
[16:04:40.497] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:40.497] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:04:40.497] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:04:40.498] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:04:40.921] INFO: PixTestAlive::maskTest() done
[16:04:40.921] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:04:40.944] INFO: ----------------------------------------------------------------------
[16:04:40.944] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:04:40.944] INFO: ----------------------------------------------------------------------
[16:04:40.946] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:41.323] INFO: Expecting 41600 events.
[16:04:45.704] INFO: 41600 events read in total (3665ms).
[16:04:45.705] INFO: Test took 4759ms.
[16:04:45.716] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:45.716] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[16:04:45.716] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:04:46.087] INFO: PixTestAlive::addressDecodingTest() done
[16:04:46.087] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:04:46.087] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[16:04:50.771] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:04:50.772] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C3_V0 -> PixelAlive_mod
[16:04:53.635] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:04:53.635] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:04:53.635] INFO: ----------------------------------------------------------------------
[16:04:53.635] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:04:53.635] INFO: ----------------------------------------------------------------------
[16:04:53.775] INFO: Expecting 768 events.
[16:04:54.907] INFO: 768 events read in total (417ms).
[16:04:54.907] INFO: Test took 1266ms.
[16:04:54.913] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:55.710] INFO: Expecting 41600 events.
[16:04:59.540] INFO: 41600 events read in total (3303ms).
[16:04:59.545] INFO: Test took 4632ms.
[16:04:59.694] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:59.694] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 877329
[16:04:59.694] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:04:59.694] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:04:59.757] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:00.422] INFO: Expecting 41600 events.
[16:05:04.349] INFO: 41600 events read in total (3400ms).
[16:05:04.354] INFO: Test took 4596ms.
[16:05:04.502] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:04.502] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 883968
[16:05:04.502] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:05:04.502] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:05:04.568] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:05.233] INFO: Expecting 41600 events.
[16:05:09.191] INFO: 41600 events read in total (3431ms).
[16:05:09.196] INFO: Test took 4628ms.
[16:05:09.342] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:09.342] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 886140
[16:05:09.342] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:05:09.343] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:05:09.409] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:10.074] INFO: Expecting 41600 events.
[16:05:14.052] INFO: 41600 events read in total (3451ms).
[16:05:14.057] INFO: Test took 4648ms.
[16:05:14.199] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:14.199] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 888392
[16:05:14.199] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:05:14.200] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:05:14.266] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:14.931] INFO: Expecting 41600 events.
[16:05:18.900] INFO: 41600 events read in total (3442ms).
[16:05:18.905] INFO: Test took 4639ms.
[16:05:19.049] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:19.049] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 888197
[16:05:19.049] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:05:19.050] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:05:19.117] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:19.782] INFO: Expecting 41600 events.
[16:05:23.774] INFO: 41600 events read in total (3465ms).
[16:05:23.779] INFO: Test took 4661ms.
[16:05:23.923] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:23.923] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 889623
[16:05:23.923] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:05:23.924] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:05:23.991] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:24.656] INFO: Expecting 41600 events.
[16:05:28.666] INFO: 41600 events read in total (3483ms).
[16:05:28.671] INFO: Test took 4680ms.
[16:05:28.816] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:28.817] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 889911
[16:05:28.817] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:05:28.817] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:05:28.885] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:29.550] INFO: Expecting 41600 events.
[16:05:33.548] INFO: 41600 events read in total (3472ms).
[16:05:33.553] INFO: Test took 4668ms.
[16:05:33.700] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:33.701] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 888061
[16:05:33.701] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:05:33.701] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:05:33.768] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:34.433] INFO: Expecting 41600 events.
[16:05:38.447] INFO: 41600 events read in total (3488ms).
[16:05:38.453] INFO: Test took 4685ms.
[16:05:38.601] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:38.601] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 890951
[16:05:38.601] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:05:38.601] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:05:38.666] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:39.332] INFO: Expecting 41600 events.
[16:05:43.349] INFO: 41600 events read in total (3491ms).
[16:05:43.354] INFO: Test took 4687ms.
[16:05:43.498] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:43.498] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 889587
[16:05:43.498] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:05:43.498] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:05:43.565] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:44.230] INFO: Expecting 41600 events.
[16:05:48.234] INFO: 41600 events read in total (3477ms).
[16:05:48.239] INFO: Test took 4674ms.
[16:05:48.385] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:48.385] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 887803
[16:05:48.385] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:05:48.385] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:05:48.453] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:49.117] INFO: Expecting 41600 events.
[16:05:53.124] INFO: 41600 events read in total (3480ms).
[16:05:53.129] INFO: Test took 4676ms.
[16:05:53.274] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:53.274] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 888974
[16:05:53.274] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:05:53.275] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:05:53.343] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:54.007] INFO: Expecting 41600 events.
[16:05:58.012] INFO: 41600 events read in total (3478ms).
[16:05:58.018] INFO: Test took 4675ms.
[16:05:58.161] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:58.162] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 887389
[16:05:58.162] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:05:58.162] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:05:58.230] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:58.896] INFO: Expecting 41600 events.
[16:06:02.895] INFO: 41600 events read in total (3472ms).
[16:06:02.900] INFO: Test took 4670ms.
[16:06:03.044] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:03.044] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 888428
[16:06:03.044] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:06:03.044] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:03.113] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:03.778] INFO: Expecting 41600 events.
[16:06:07.782] INFO: 41600 events read in total (3478ms).
[16:06:07.787] INFO: Test took 4674ms.
[16:06:07.933] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:07.933] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 890405
[16:06:07.933] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:06:07.934] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:08.002] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:08.668] INFO: Expecting 41600 events.
[16:06:12.674] INFO: 41600 events read in total (3480ms).
[16:06:12.679] INFO: Test took 4677ms.
[16:06:12.823] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:12.823] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 889313
[16:06:12.823] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:06:12.824] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:12.891] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:13.556] INFO: Expecting 41600 events.
[16:06:17.561] INFO: 41600 events read in total (3478ms).
[16:06:17.566] INFO: Test took 4675ms.
[16:06:17.711] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:17.711] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 887770
[16:06:17.711] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:06:17.711] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:17.780] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:18.446] INFO: Expecting 41600 events.
[16:06:22.451] INFO: 41600 events read in total (3479ms).
[16:06:22.456] INFO: Test took 4676ms.
[16:06:22.601] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:22.601] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 886885
[16:06:22.601] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:06:22.601] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:22.669] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:23.335] INFO: Expecting 41600 events.
[16:06:27.319] INFO: 41600 events read in total (3457ms).
[16:06:27.324] INFO: Test took 4655ms.
[16:06:27.469] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:27.469] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 887781
[16:06:27.469] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:06:27.469] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:27.538] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:28.204] INFO: Expecting 41600 events.
[16:06:32.010] INFO: 41600 events read in total (3279ms).
[16:06:32.015] INFO: Test took 4477ms.
[16:06:32.159] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:32.159] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 879185
[16:06:32.159] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:06:32.159] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:32.448] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 148 eff = 0.998702
[16:06:32.448] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 167 eff = 0.999207
[16:06:32.448] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 160 eff = 0.997332
[16:06:32.449] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 154 eff = 0.995938
[16:06:32.449] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 152 eff = 0.995553
[16:06:32.449] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 152 eff = 0.995264
[16:06:32.449] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 171 eff = 0.99524
[16:06:32.450] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 190 eff = 0.996731
[16:06:32.450] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 162 eff = 0.996154
[16:06:32.450] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 158 eff = 0.994736
[16:06:32.450] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 146 eff = 0.995841
[16:06:32.451] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 155 eff = 0.995457
[16:06:32.451] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 163 eff = 0.995697
[16:06:32.451] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 150 eff = 0.997644
[16:06:32.451] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 161 eff = 0.999038
[16:06:32.451] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 139 eff = 0.998894
[16:06:43.171] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:06:43.171] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:06:43.171] INFO: ----------------------------------------------------------------------
[16:06:43.171] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:06:43.171] INFO: ----------------------------------------------------------------------
[16:06:43.172] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:06:43.172] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:06:43.172] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:06:43.172] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:06:43.172] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:06:43.172] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:06:43.172] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:06:43.172] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:06:43.172] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:06:43.172] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:06:43.172] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:06:43.172] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:06:43.191] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:43.858] INFO: Expecting 208000 events.
[16:07:00.823] INFO: 208000 events read in total (16438ms).
[16:07:00.843] INFO: Test took 17652ms.
[16:07:01.542] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:01.542] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4132685
[16:07:01.542] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:07:01.543] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:07:02.049] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:07:02.049] INFO: number of red-efficiency pixels: 356 188 458 714 838 808 825 706 703 931 807 807 749 437 208 244
[16:07:02.049] INFO: number of X-ray hits detected: 169772 115735 199633 300979 321084 315896 318740 293821 298350 331096 317856 301462 304482 203449 121677 152094
[16:07:02.049] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:07:02.050] INFO: number of Vcal hits detected: 207622 207798 207411 207146 207018 207046 207033 207174 207215 206868 207036 207067 207152 207488 207779 207743
[16:07:02.050] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.6 99.6 99.6 99.6 99.6 99.6 99.5 99.6 99.6 99.6 99.8 99.9 99.9
[16:07:02.050] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.5 99.5 99.5 99.6 99.6 99.5 99.5 99.6 99.6 99.8 99.9 99.9
[16:07:02.050] INFO: X-ray hit rate [MHz/cm2]: 49.8 33.9 58.5 88.2 94.1 92.6 93.4 86.1 87.4 97.0 93.2 88.4 89.2 59.6 35.7 44.6
[16:07:02.050] INFO: PixTestHighRate::doXPixelAlive() done
[16:07:02.095] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:07:02.095] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:07:02.095] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:07:02.095] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:07:02.095] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:07:02.095] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:07:02.095] INFO: PixTest:: pg_setup set to default.
[16:07:06.251] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:07:06.251] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[16:07:07.707] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[16:07:07.707] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[16:07:07.708] INFO: ----------------------------------------------------------------------
[16:07:07.708] INFO: PixTestHighRate::runDaq() running for 2 seconds
[16:07:07.708] INFO: ----------------------------------------------------------------------
[16:07:07.908] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[16:07:07.908] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[16:07:07.908] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[16:07:07.908] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[16:07:07.908] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[16:07:07.908] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[16:07:07.908] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[16:07:07.908] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[16:07:07.908] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[16:07:07.908] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[16:07:07.908] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[16:07:07.908] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[16:07:08.865] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[16:07:10.868] INFO: data taking finished, elapsed time: 2 seconds.
[16:07:12.822] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39824 events with 775284 pixels
[16:07:12.827] INFO: PixTest:: pg_setup set to default.
[16:07:12.933] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[16:07:12.933] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[16:07:12.933] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[16:07:12.933] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[16:07:12.933] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[16:07:12.934] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[16:07:12.934] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[16:07:12.934] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[16:07:12.934] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[16:07:12.934] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[16:07:12.934] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[16:07:12.934] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[16:07:12.935] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[16:07:12.935] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[16:07:12.935] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[16:07:12.935] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[16:07:12.935] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[16:07:12.935] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[16:07:12.935] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[16:07:12.935] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[16:07:12.935] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[16:07:12.935] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[16:07:12.935] INFO: Pixels without X-ray hits (per ROC): 323 880 524 109 99 87 78 90 102 81 93 180 78 473 807 382
[16:07:12.936] INFO: PixTestHighRate::doRunDaq() done
[16:07:17.355] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:07:17.356] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C12_V0 -> hitMap_daqbbtest_mod
[16:07:19.035] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:07:19.036] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[16:07:19.037] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[16:07:19.037] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[16:07:19.037] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:07:19.037] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:07:19.037] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:07:19.037] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:07:19.037] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:07:19.037] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:07:19.037] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:07:19.037] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:07:19.038] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:07:19.038] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:07:19.147] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:07:19.148] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:07:19.161] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:07:19.161] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:07:19.161] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:07:19.161] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:07:19.161] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:07:19.161] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:07:19.164] QUIET: Connection to board 58 closed.
[16:07:19.244] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-904-gd94b9f2 on branch master