Test Date: 1973-11-29 16:33
Analysis date: 2016-10-05 14:47
Logfile
hrData_40.log
[15:42:26.016] INFO: *** Welcome to pxar ***
[15:42:26.016] INFO: *** Today: 2016/10/04
[15:42:26.039] INFO: *** Version: v1.9.0-818-g96727
[15:42:26.039] INFO: readRocDacs: data/mq634/dacParameters35_C0.dat .. data/mq634/dacParameters35_C15.dat
[15:42:26.040] INFO: readTbmDacs: data/mq634/tbmParameters_C0a.dat .. data/mq634/tbmParameters_C0b.dat
[15:42:26.041] INFO: readMaskFile: data/mq634/defaultMaskFile.dat
[15:42:26.041] INFO: readTrimFile: data/mq634/trimParameters35_C0.dat .. data/mq634/trimParameters35_C15.dat
[15:42:26.189] INFO: clk: 4
[15:42:26.189] INFO: ctr: 4
[15:42:26.189] INFO: sda: 19
[15:42:26.189] INFO: tin: 9
[15:42:26.189] INFO: level: 15
[15:42:26.189] INFO: triggerdelay: 0
[15:42:26.189] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:42:26.189] INFO: Log level: DEBUG
[15:42:26.200] QUIET: Connection to board DTB_WRPRHI opened.
[15:42:26.204] 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:
------------------------------------------------------
[15:42:26.207] INFO: RPC call hashes of host and DTB match: 398089610
[15:42:27.808] INFO: DUT info:
[15:42:27.808] INFO: The DUT currently contains the following objects:
[15:42:27.808] INFO: 2 TBM Cores tbm08c (2 ON)
[15:42:27.808] INFO: TBM Core alpha (0): 7 registers set
[15:42:27.808] INFO: TBM Core beta (1): 7 registers set
[15:42:27.808] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:42:27.808] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.808] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.808] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.808] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.808] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.808] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.808] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.808] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.808] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.808] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.808] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.809] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.809] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.809] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.809] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.809] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:42:27.809] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:42:27.810] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:42:27.811] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:42:27.812] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:42:27.814] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33648640
[15:42:27.814] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x198e0e0
[15:42:27.814] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1730370
[15:42:27.814] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f4f09d94010
[15:42:27.814] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f4f0ffff510
[15:42:27.814] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33656832 fPxarMemory = 0x7f4f09d94010
[15:42:27.815] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[15:42:27.817] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[15:42:27.817] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:42:28.314] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1d7cf60
[15:42:28.376] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:42:28.377] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:42:28.377] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:42:28.383] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:42:28.383] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:42:28.383] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:42:28.395] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:42:28.395] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:42:28.402] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:42:28.402] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:42:28.431] INFO: PixTestCmd::init()
[15:42:28.446] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:42:28.446] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:42:28.446] INFO: readGainPedestalParameters data/mq634/phCalibrationFitErr35_C0.dat .. data/mq634/phCalibrationFitErr35_C15.dat
[15:42:28.695] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:42:28.695] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:42:28.709] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:42:28.715] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:42:28.715] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:42:28.715] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:42:28.735] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:42:28.735] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:42:28.735] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:42:28.735] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:42:28.735] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:42:28.742] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:42:28.742] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:42:28.757] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:42:28.757] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:42:28.757] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:42:28.757] INFO: readReadbackCal: data/mq634/readbackCal_C0.dat .. data/mq634/readbackCal_C15.dat
[15:42:28.767] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:42:28.775] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:42:28.775] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:42:28.775] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:42:28.775] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:42:28.787] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:42:28.787] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:42:35.043] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[15:42:36.139] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[15:42:36.139] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[15:42:36.139] INFO: ----------------------------------------------------------------------
[15:42:36.139] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[15:42:36.139] INFO: ----------------------------------------------------------------------
[15:42:37.113] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[15:42:49.850] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[15:42:49.854] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:43:17.068] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1254930 events.
[15:43:22.684] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1254930, pixels seen in all events: 5192816
[15:43:22.849] INFO: Resuming triggers.
[15:43:35.590] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[15:43:35.628] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:44:02.784] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1255200 events.
[15:44:08.429] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1255200, pixels seen in all events: 5188492
[15:44:08.662] INFO: Resuming triggers.
[15:44:21.410] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[15:44:21.445] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:44:48.552] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1255929 events.
[15:44:54.262] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1255929, pixels seen in all events: 5180364
[15:44:54.431] INFO: Resuming triggers.
[15:45:07.180] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[15:45:07.211] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:45:34.319] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1256025 events.
[15:45:39.993] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1256025, pixels seen in all events: 5178789
[15:45:40.160] INFO: Resuming triggers.
[15:45:49.264] INFO: data taking finished, elapsed time: 60 seconds.
[15:45:49.460] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:46:08.867] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 896904 events.
[15:46:12.860] DEBUG: <PixTestXray.cc/processData:L823> # events read: 896904, pixels seen in all events: 3694538
[15:46:12.938] INFO: PixTest:: pg_setup set to default.
[15:46:13.025] INFO: PixTestXray::doPhRun() done
[15:46:21.979] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:46:21.980] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 388.3mA
[15:46:21.981] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[15:46:21.981] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:46:21.981] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:46:21.981] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:46:21.981] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:46:21.981] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:46:21.981] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:46:21.981] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:46:21.981] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:46:21.981] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:46:21.981] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:46:21.981] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:46:21.981] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:46:21.981] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:46:21.981] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:46:21.982] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:46:21.982] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:46:21.982] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:46:21.982] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:46:21.982] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:46:22.256] QUIET: Connection to board 58 closed.
[15:46:22.335] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[15:46:28.129] INFO: *** Welcome to pxar ***
[15:46:28.129] INFO: *** Today: 2016/10/04
[15:46:28.158] INFO: *** Version: v1.9.0-818-g96727
[15:46:28.158] INFO: readRocDacs: data/mq634/dacParameters35_C0.dat .. data/mq634/dacParameters35_C15.dat
[15:46:28.159] INFO: readTbmDacs: data/mq634/tbmParameters_C0a.dat .. data/mq634/tbmParameters_C0b.dat
[15:46:28.159] INFO: readMaskFile: data/mq634/defaultMaskFile.dat
[15:46:28.159] INFO: readTrimFile: data/mq634/trimParameters35_C0.dat .. data/mq634/trimParameters35_C15.dat
[15:46:28.309] INFO: clk: 4
[15:46:28.309] INFO: ctr: 4
[15:46:28.309] INFO: sda: 19
[15:46:28.309] INFO: tin: 9
[15:46:28.309] INFO: level: 15
[15:46:28.309] INFO: triggerdelay: 0
[15:46:28.309] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:46:28.309] INFO: Log level: DEBUG
[15:46:28.320] QUIET: Connection to board DTB_WRPRHI opened.
[15:46:28.323] 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:
------------------------------------------------------
[15:46:28.326] INFO: RPC call hashes of host and DTB match: 398089610
[15:46:29.928] INFO: DUT info:
[15:46:29.928] INFO: The DUT currently contains the following objects:
[15:46:29.928] INFO: 2 TBM Cores tbm08c (2 ON)
[15:46:29.928] INFO: TBM Core alpha (0): 7 registers set
[15:46:29.928] INFO: TBM Core beta (1): 7 registers set
[15:46:29.928] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:46:29.928] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.928] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.928] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.928] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.928] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.928] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.928] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.928] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.928] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.928] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.928] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.928] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.928] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.928] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.928] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.928] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:46:29.929] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:46:29.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:46:29.931] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:46:29.932] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:46:29.932] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:46:29.932] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:46:29.932] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:46:29.932] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:46:29.932] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:46:29.932] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:46:29.932] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:46:29.932] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:46:29.932] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:46:29.932] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:46:29.932] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:46:29.934] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32722944
[15:46:29.934] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x21370e0
[15:46:29.934] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1ed9370
[15:46:29.934] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f1345d94010
[15:46:29.934] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f134bfff510
[15:46:29.934] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32731136 fPxarMemory = 0x7f1345d94010
[15:46:29.935] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[15:46:29.936] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[15:46:29.937] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:46:30.429] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2525fd0
[15:46:30.490] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:46:30.490] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:46:30.491] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:46:30.496] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:46:30.496] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:46:30.496] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:46:30.507] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:46:30.507] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:46:30.515] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:46:30.515] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:46:30.541] INFO: PixTestCmd::init()
[15:46:30.566] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:46:30.566] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:46:30.566] INFO: readGainPedestalParameters data/mq634/phCalibrationFitErr35_C0.dat .. data/mq634/phCalibrationFitErr35_C15.dat
[15:46:30.812] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:46:30.812] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:46:30.826] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:46:30.832] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:46:30.832] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:46:30.832] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:46:30.854] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:46:30.854] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:46:30.854] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:46:30.854] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:46:30.854] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:46:30.861] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:46:30.861] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:46:30.876] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:46:30.876] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:46:30.876] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:46:30.876] INFO: readReadbackCal: data/mq634/readbackCal_C0.dat .. data/mq634/readbackCal_C15.dat
[15:46:30.886] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:46:30.894] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:46:30.894] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:46:30.894] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:46:30.894] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:46:30.907] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:46:30.907] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:46:43.947] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[15:46:45.963] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[15:46:45.963] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[15:46:45.963] INFO: ----------------------------------------------------------------------
[15:46:45.963] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[15:46:45.963] INFO: ----------------------------------------------------------------------
[15:46:46.938] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[15:46:54.821] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:46:54.824] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:47:19.893] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 776688 events.
[15:47:31.637] DEBUG: <PixTestXray.cc/processData:L823> # events read: 776688, pixels seen in all events: 10932725
[15:47:31.895] INFO: Resuming triggers.
[15:47:39.784] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[15:47:39.823] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:48:04.875] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 777214 events.
[15:48:16.605] DEBUG: <PixTestXray.cc/processData:L823> # events read: 777214, pixels seen in all events: 10925334
[15:48:16.775] INFO: Resuming triggers.
[15:48:24.670] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:48:24.771] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:48:49.835] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 777807 events.
[15:49:01.558] DEBUG: <PixTestXray.cc/processData:L823> # events read: 777807, pixels seen in all events: 10918423
[15:49:01.763] INFO: Resuming triggers.
[15:49:09.658] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[15:49:09.730] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:49:34.838] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 777831 events.
[15:49:46.579] DEBUG: <PixTestXray.cc/processData:L823> # events read: 777831, pixels seen in all events: 10917697
[15:49:46.818] INFO: Resuming triggers.
[15:49:54.715] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[15:49:54.752] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:50:19.747] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 777903 events.
[15:50:31.467] DEBUG: <PixTestXray.cc/processData:L823> # events read: 777903, pixels seen in all events: 10917201
[15:50:31.671] INFO: Resuming triggers.
[15:50:39.567] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[15:50:39.629] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:51:04.709] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 777843 events.
[15:51:16.408] DEBUG: <PixTestXray.cc/processData:L823> # events read: 777843, pixels seen in all events: 10918086
[15:51:16.646] INFO: Resuming triggers.
[15:51:24.545] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[15:51:24.585] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:51:49.625] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 778199 events.
[15:52:01.327] DEBUG: <PixTestXray.cc/processData:L823> # events read: 778199, pixels seen in all events: 10913836
[15:52:01.494] INFO: Resuming triggers.
[15:52:06.321] INFO: data taking finished, elapsed time: 60 seconds.
[15:52:06.518] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:52:21.893] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 475569 events.
[15:52:28.576] DEBUG: <PixTestXray.cc/processData:L823> # events read: 475569, pixels seen in all events: 6671426
[15:52:28.695] INFO: PixTest:: pg_setup set to default.
[15:52:28.736] INFO: PixTestXray::doPhRun() done
[15:52:35.491] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:52:35.492] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 388.3mA
[15:52:35.493] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[15:52:35.493] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[15:52:35.493] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:52:35.493] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:52:35.493] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:52:35.493] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:52:35.493] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:52:35.493] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:52:35.493] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:52:35.493] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:52:35.493] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:52:35.493] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:52:35.493] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:52:35.494] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:52:35.494] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:52:35.494] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:52:35.494] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:52:35.494] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:52:35.494] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:52:35.494] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:52:35.796] QUIET: Connection to board 58 closed.
[15:52:35.875] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[15:36:24.030] INFO: *** Welcome to pxar ***
[15:36:24.030] INFO: *** Today: 2016/10/04
[15:36:24.055] INFO: *** Version: v1.9.0-818-g96727
[15:36:24.055] INFO: readRocDacs: data/mq634/dacParameters35_C0.dat .. data/mq634/dacParameters35_C15.dat
[15:36:24.056] INFO: readTbmDacs: data/mq634/tbmParameters_C0a.dat .. data/mq634/tbmParameters_C0b.dat
[15:36:24.057] INFO: readMaskFile: data/mq634/defaultMaskFile.dat
[15:36:24.057] INFO: readTrimFile: data/mq634/trimParameters35_C0.dat .. data/mq634/trimParameters35_C15.dat
[15:36:24.206] INFO: clk: 4
[15:36:24.206] INFO: ctr: 4
[15:36:24.206] INFO: sda: 19
[15:36:24.206] INFO: tin: 9
[15:36:24.206] INFO: level: 15
[15:36:24.206] INFO: triggerdelay: 0
[15:36:24.206] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:36:24.206] INFO: Log level: DEBUG
[15:36:24.218] QUIET: Connection to board DTB_WRPRHI opened.
[15:36:24.221] 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:
------------------------------------------------------
[15:36:24.224] INFO: RPC call hashes of host and DTB match: 398089610
[15:36:25.832] INFO: DUT info:
[15:36:25.832] INFO: The DUT currently contains the following objects:
[15:36:25.832] INFO: 2 TBM Cores tbm08c (2 ON)
[15:36:25.832] INFO: TBM Core alpha (0): 7 registers set
[15:36:25.832] INFO: TBM Core beta (1): 7 registers set
[15:36:25.832] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:36:25.832] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.832] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.832] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.832] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.832] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.832] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.832] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.832] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.832] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.832] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.832] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.832] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.832] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.832] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.832] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.832] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:36:25.833] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:36:25.834] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:36:25.835] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:36:25.836] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:36:25.836] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:36:25.836] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:36:25.836] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:36:25.836] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:36:25.836] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:36:25.838] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35336192
[15:36:25.838] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x128f0e0
[15:36:25.838] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1031370
[15:36:25.838] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f9531d94010
[15:36:25.838] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f9537fff510
[15:36:25.838] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35344384 fPxarMemory = 0x7f9531d94010
[15:36:25.839] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[15:36:25.840] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[15:36:25.840] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:36:26.335] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x167e030
[15:36:26.400] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:36:26.400] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:36:26.400] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:36:26.405] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:36:26.406] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:36:26.406] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:36:26.417] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:36:26.417] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:36:26.435] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:36:26.435] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:36:26.454] INFO: PixTestCmd::init()
[15:36:26.468] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:36:26.469] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:36:26.469] INFO: readGainPedestalParameters data/mq634/phCalibrationFitErr35_C0.dat .. data/mq634/phCalibrationFitErr35_C15.dat
[15:36:26.724] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:36:26.724] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:36:26.738] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:36:26.745] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:36:26.745] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:36:26.745] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:36:26.764] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:36:26.764] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:36:26.764] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:36:26.764] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:36:26.764] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:36:26.770] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:36:26.771] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:36:26.785] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:36:26.785] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:36:26.786] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:36:26.786] INFO: readReadbackCal: data/mq634/readbackCal_C0.dat .. data/mq634/readbackCal_C15.dat
[15:36:26.796] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:36:26.804] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:36:26.804] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:36:26.804] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:36:26.804] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:36:26.818] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:36:26.818] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:36:42.734] INFO: ######################################################################
[15:36:42.734] INFO: PixTestAlive::doTest()
[15:36:42.734] INFO: ######################################################################
[15:36:42.738] INFO: ----------------------------------------------------------------------
[15:36:42.738] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:36:42.738] INFO: ----------------------------------------------------------------------
[15:36:42.740] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:36:43.118] INFO: Expecting 41600 events.
[15:36:47.507] INFO: 41600 events read in total (3672ms).
[15:36:47.654] INFO: Test took 4914ms.
[15:36:47.665] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:47.665] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:36:47.665] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:36:47.941] INFO: PixTestAlive::aliveTest() done
[15:36:47.941] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[15:36:47.941] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 21 14 27 23 33 37 40 43 35 35 27 33 28 18 18 17
[15:36:47.969] INFO: ----------------------------------------------------------------------
[15:36:47.969] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:36:47.969] INFO: ----------------------------------------------------------------------
[15:36:47.971] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:36:48.347] INFO: Expecting 41600 events.
[15:36:51.461] INFO: 41600 events read in total (2398ms).
[15:36:51.461] INFO: Test took 3490ms.
[15:36:51.462] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:51.462] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:36:51.462] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:36:51.462] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:36:51.884] INFO: PixTestAlive::maskTest() done
[15:36:51.884] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:36:51.906] INFO: ----------------------------------------------------------------------
[15:36:51.906] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:36:51.906] INFO: ----------------------------------------------------------------------
[15:36:51.909] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:36:52.286] INFO: Expecting 41600 events.
[15:36:56.663] INFO: 41600 events read in total (3662ms).
[15:36:56.664] INFO: Test took 4755ms.
[15:36:56.675] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:56.675] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:36:56.675] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:36:57.046] INFO: PixTestAlive::addressDecodingTest() done
[15:36:57.046] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:36:57.046] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:37:00.267] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:37:00.267] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[15:37:02.371] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:37:02.371] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:37:02.371] INFO: ----------------------------------------------------------------------
[15:37:02.371] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:37:02.371] INFO: ----------------------------------------------------------------------
[15:37:02.510] INFO: Expecting 768 events.
[15:37:03.644] INFO: 768 events read in total (418ms).
[15:37:03.645] INFO: Test took 1268ms.
[15:37:03.651] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:04.448] INFO: Expecting 41600 events.
[15:37:07.770] INFO: 41600 events read in total (2795ms).
[15:37:07.775] INFO: Test took 4124ms.
[15:37:07.853] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:07.853] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 442257
[15:37:07.853] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:37:07.853] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:07.890] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:08.556] INFO: Expecting 41600 events.
[15:37:11.980] INFO: 41600 events read in total (2897ms).
[15:37:11.984] INFO: Test took 4094ms.
[15:37:12.057] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:12.057] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 446639
[15:37:12.057] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:37:12.058] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:12.095] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:12.760] INFO: Expecting 41600 events.
[15:37:16.213] INFO: 41600 events read in total (2926ms).
[15:37:16.216] INFO: Test took 4121ms.
[15:37:16.291] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:16.292] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 448588
[15:37:16.292] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:37:16.292] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:16.331] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:16.997] INFO: Expecting 41600 events.
[15:37:20.451] INFO: 41600 events read in total (2927ms).
[15:37:20.454] INFO: Test took 4123ms.
[15:37:20.528] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:20.528] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 449152
[15:37:20.528] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:37:20.529] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:20.566] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:21.232] INFO: Expecting 41600 events.
[15:37:24.690] INFO: 41600 events read in total (2932ms).
[15:37:24.694] INFO: Test took 4128ms.
[15:37:24.768] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:24.768] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 450204
[15:37:24.768] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:37:24.769] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:24.806] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:25.471] INFO: Expecting 41600 events.
[15:37:28.935] INFO: 41600 events read in total (2937ms).
[15:37:28.938] INFO: Test took 4132ms.
[15:37:29.013] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:29.013] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 451448
[15:37:29.013] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:37:29.013] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:29.051] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:29.716] INFO: Expecting 41600 events.
[15:37:33.180] INFO: 41600 events read in total (2937ms).
[15:37:33.184] INFO: Test took 4133ms.
[15:37:33.259] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:33.259] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 449101
[15:37:33.259] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:37:33.260] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:33.299] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:33.965] INFO: Expecting 41600 events.
[15:37:37.433] INFO: 41600 events read in total (2941ms).
[15:37:37.436] INFO: Test took 4137ms.
[15:37:37.510] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:37.510] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 449420
[15:37:37.510] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:37:37.511] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:37.548] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:38.213] INFO: Expecting 41600 events.
[15:37:41.689] INFO: 41600 events read in total (2949ms).
[15:37:41.692] INFO: Test took 4144ms.
[15:37:41.768] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:41.768] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 451231
[15:37:41.768] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:37:41.768] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:41.807] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:42.474] INFO: Expecting 41600 events.
[15:37:45.944] INFO: 41600 events read in total (2944ms).
[15:37:45.947] INFO: Test took 4140ms.
[15:37:46.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:46.022] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 450277
[15:37:46.022] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:37:46.022] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:46.061] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:46.727] INFO: Expecting 41600 events.
[15:37:50.196] INFO: 41600 events read in total (2942ms).
[15:37:50.199] INFO: Test took 4138ms.
[15:37:50.274] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:50.274] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 450593
[15:37:50.274] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:37:50.275] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:50.312] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:50.977] INFO: Expecting 41600 events.
[15:37:54.443] INFO: 41600 events read in total (2939ms).
[15:37:54.446] INFO: Test took 4134ms.
[15:37:54.521] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:54.521] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 450120
[15:37:54.521] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:37:54.521] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:54.559] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:55.226] INFO: Expecting 41600 events.
[15:37:58.706] INFO: 41600 events read in total (2954ms).
[15:37:58.709] INFO: Test took 4150ms.
[15:37:58.783] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:58.783] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 449838
[15:37:58.783] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:37:58.783] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:58.821] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:59.487] INFO: Expecting 41600 events.
[15:38:02.963] INFO: 41600 events read in total (2949ms).
[15:38:02.966] INFO: Test took 4145ms.
[15:38:03.041] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:03.041] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 449886
[15:38:03.041] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:38:03.041] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:03.079] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:03.745] INFO: Expecting 41600 events.
[15:38:07.216] INFO: 41600 events read in total (2945ms).
[15:38:07.219] INFO: Test took 4140ms.
[15:38:07.294] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:07.294] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 450113
[15:38:07.294] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:38:07.294] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:07.332] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:07.997] INFO: Expecting 41600 events.
[15:38:11.463] INFO: 41600 events read in total (2939ms).
[15:38:11.466] INFO: Test took 4134ms.
[15:38:11.540] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:11.540] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 450348
[15:38:11.540] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:38:11.541] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:11.579] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:12.244] INFO: Expecting 41600 events.
[15:38:15.712] INFO: 41600 events read in total (2942ms).
[15:38:15.716] INFO: Test took 4137ms.
[15:38:15.791] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:15.791] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 449255
[15:38:15.791] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:38:15.791] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:15.830] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:16.496] INFO: Expecting 41600 events.
[15:38:19.962] INFO: 41600 events read in total (2939ms).
[15:38:19.965] INFO: Test took 4135ms.
[15:38:20.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:20.039] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 450289
[15:38:20.039] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:38:20.039] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:20.078] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:20.742] INFO: Expecting 41600 events.
[15:38:24.204] INFO: 41600 events read in total (2935ms).
[15:38:24.208] INFO: Test took 4130ms.
[15:38:24.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:24.283] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 448212
[15:38:24.283] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:38:24.283] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:24.322] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:24.989] INFO: Expecting 41600 events.
[15:38:28.311] INFO: 41600 events read in total (2796ms).
[15:38:28.315] INFO: Test took 3993ms.
[15:38:28.389] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:28.389] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 444683
[15:38:28.389] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:38:28.390] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:28.649] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 153 eff = 0.999639
[15:38:28.649] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 161 eff = 0.999784
[15:38:28.650] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 166 eff = 0.999519
[15:38:28.650] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 181 eff = 0.999231
[15:38:28.650] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 196 eff = 0.999111
[15:38:28.650] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 133 eff = 0.999135
[15:38:28.651] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 149 eff = 0.999063
[15:38:28.651] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 152 eff = 0.961803
[15:38:28.651] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 147 eff = 0.999255
[15:38:28.651] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 205 eff = 0.999038
[15:38:28.652] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 138 eff = 0.999063
[15:38:28.652] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 161 eff = 0.998918
[15:38:28.652] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 207 eff = 0.999207
[15:38:28.652] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 140 eff = 0.999423
[15:38:28.653] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 143 eff = 0.999808
[15:38:28.653] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 135 eff = 0.999736
[15:38:36.803] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:38:36.803] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:38:36.803] INFO: ----------------------------------------------------------------------
[15:38:36.803] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:38:36.803] INFO: ----------------------------------------------------------------------
[15:38:36.803] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:38:36.803] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:38:36.803] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:38:36.804] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:38:36.804] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:38:36.804] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:38:36.804] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:38:36.804] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:38:36.804] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:38:36.804] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:38:36.804] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:38:36.804] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:38:36.822] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:37.491] INFO: Expecting 208000 events.
[15:38:50.770] INFO: 208000 events read in total (12752ms).
[15:38:50.781] INFO: Test took 13959ms.
[15:38:51.115] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:51.115] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1972697
[15:38:51.115] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:38:51.116] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:51.479] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 100 0 0 1 0 0 0 0 0
[15:38:51.479] INFO: number of red-efficiency pixels: 102 65 120 222 203 230 186 391 190 220 244 232 215 161 74 100
[15:38:51.479] INFO: number of X-ray hits detected: 89972 56391 97231 139468 147817 147745 147476 133033 132880 153148 149374 142509 146932 96138 53774 72350
[15:38:51.479] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:38:51.479] INFO: number of Vcal hits detected: 207895 207935 207875 207770 207792 207764 207803 199884 207804 207770 207699 207760 207779 207832 207926 207897
[15:38:51.479] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 98.4 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[15:38:51.479] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 96.1 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[15:38:51.479] INFO: X-ray hit rate [MHz/cm2]: 26.4 16.5 28.5 40.9 43.3 43.3 43.2 39.0 38.9 44.9 43.8 41.8 43.1 28.2 15.8 21.2
[15:38:51.479] INFO: PixTestHighRate::doXPixelAlive() done
[15:38:51.524] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:38:51.524] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:38:51.524] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:38:51.524] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:38:51.524] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:38:51.524] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:38:51.524] INFO: PixTest:: pg_setup set to default.
[15:38:56.435] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:38:56.435] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[15:38:58.787] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[15:38:58.787] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[15:38:58.787] INFO: ----------------------------------------------------------------------
[15:38:58.787] INFO: PixTestHighRate::runDaq() running for 2 seconds
[15:38:58.787] INFO: ----------------------------------------------------------------------
[15:38:58.986] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[15:38:58.986] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[15:38:58.986] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[15:38:58.986] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[15:38:58.986] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[15:38:58.986] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[15:38:58.986] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[15:38:58.986] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[15:38:58.986] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[15:38:58.986] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[15:38:58.986] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[15:38:58.986] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[15:38:59.943] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[15:39:01.946] INFO: data taking finished, elapsed time: 2 seconds.
[15:39:03.413] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39825 events with 361318 pixels
[15:39:03.419] INFO: PixTest:: pg_setup set to default.
[15:39:03.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[15:39:03.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[15:39:03.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[15:39:03.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[15:39:03.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[15:39:03.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[15:39:03.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[15:39:03.562] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[15:39:03.562] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[15:39:03.562] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[15:39:03.562] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[15:39:03.562] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[15:39:03.562] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[15:39:03.562] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[15:39:03.563] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[15:39:03.563] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[15:39:03.563] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[15:39:03.563] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[15:39:03.563] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[15:39:03.563] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[15:39:03.563] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[15:39:03.563] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[15:39:03.563] INFO: Pixels without X-ray hits (per ROC): 724 1415 791 218 205 220 208 210 281 219 236 318 199 734 1415 815
[15:39:03.563] INFO: PixTestHighRate::doRunDaq() done
[15:39:08.827] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:39:08.827] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C12_V0 -> hitMap_daqbbtest_mod
[15:39:16.019] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:39:16.020] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 388.3mA
[15:39:16.020] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[15:39:16.020] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[15:39:16.021] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:39:16.021] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:39:16.021] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:39:16.021] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:39:16.021] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:39:16.021] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:39:16.021] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:39:16.021] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:39:16.021] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:39:16.021] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:39:16.127] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:39:16.127] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:39:16.140] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:39:16.140] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:39:16.141] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:39:16.141] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:39:16.141] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:39:16.141] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:39:16.143] QUIET: Connection to board 58 closed.
[15:39:16.223] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[15:33:06.802] INFO: *** Welcome to pxar ***
[15:33:06.802] INFO: *** Today: 2016/10/04
[15:33:06.830] INFO: *** Version: v1.9.0-818-g96727
[15:33:06.830] INFO: readRocDacs: data/mq634/dacParameters35_C0.dat .. data/mq634/dacParameters35_C15.dat
[15:33:06.831] INFO: readTbmDacs: data/mq634/tbmParameters_C0a.dat .. data/mq634/tbmParameters_C0b.dat
[15:33:06.831] INFO: readMaskFile: data/mq634/defaultMaskFile.dat
[15:33:06.831] INFO: readTrimFile: data/mq634/trimParameters35_C0.dat .. data/mq634/trimParameters35_C15.dat
[15:33:06.980] INFO: clk: 4
[15:33:06.980] INFO: ctr: 4
[15:33:06.980] INFO: sda: 19
[15:33:06.980] INFO: tin: 9
[15:33:06.980] INFO: level: 15
[15:33:06.980] INFO: triggerdelay: 0
[15:33:06.980] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:33:06.980] INFO: Log level: DEBUG
[15:33:06.993] QUIET: Connection to board DTB_WRPRHI opened.
[15:33:06.997] 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:
------------------------------------------------------
[15:33:06.999] INFO: RPC call hashes of host and DTB match: 398089610
[15:33:08.600] INFO: DUT info:
[15:33:08.600] INFO: The DUT currently contains the following objects:
[15:33:08.600] INFO: 2 TBM Cores tbm08c (2 ON)
[15:33:08.600] INFO: TBM Core alpha (0): 7 registers set
[15:33:08.600] INFO: TBM Core beta (1): 7 registers set
[15:33:08.600] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:33:08.600] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.600] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.600] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.601] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.601] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.601] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.601] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.601] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.601] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.601] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.601] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.601] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.601] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.601] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.601] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.601] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:33:08.601] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:33:08.601] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:33:08.601] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:33:08.601] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:33:08.601] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:33:08.601] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:33:08.601] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:33:08.601] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:33:08.601] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:33:08.601] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:33:08.601] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:33:08.601] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:33:08.601] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:33:08.601] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:33:08.602] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:33:08.603] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:33:08.604] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:33:08.606] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 36913152
[15:33:08.606] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x10020e0
[15:33:08.606] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xda4370
[15:33:08.606] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f9095393010
[15:33:08.606] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f909b5fe510
[15:33:08.606] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 36921344 fPxarMemory = 0x7f9095393010
[15:33:08.608] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[15:33:08.609] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[15:33:08.609] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:33:09.106] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x13f1030
[15:33:09.167] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:33:09.167] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:33:09.167] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:33:09.173] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:33:09.173] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:33:09.173] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:33:09.185] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:33:09.185] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:33:09.212] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:33:09.213] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:33:09.226] INFO: PixTestCmd::init()
[15:33:09.244] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:33:09.244] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:33:09.244] INFO: readGainPedestalParameters data/mq634/phCalibrationFitErr35_C0.dat .. data/mq634/phCalibrationFitErr35_C15.dat
[15:33:09.495] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:33:09.495] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:33:09.509] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:33:09.516] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:33:09.516] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:33:09.516] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:33:09.536] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:33:09.536] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:33:09.536] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:33:09.536] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:33:09.536] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:33:09.542] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:33:09.542] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:33:09.558] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:33:09.558] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:33:09.558] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:33:09.558] INFO: readReadbackCal: data/mq634/readbackCal_C0.dat .. data/mq634/readbackCal_C15.dat
[15:33:09.568] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:33:09.577] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:33:09.577] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:33:09.577] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:33:09.577] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:33:09.596] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:33:09.596] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:33:18.526] INFO: ######################################################################
[15:33:18.526] INFO: PixTestAlive::doTest()
[15:33:18.526] INFO: ######################################################################
[15:33:18.530] INFO: ----------------------------------------------------------------------
[15:33:18.530] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:33:18.530] INFO: ----------------------------------------------------------------------
[15:33:18.532] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:33:18.912] INFO: Expecting 41600 events.
[15:33:23.293] INFO: 41600 events read in total (3663ms).
[15:33:23.441] INFO: Test took 4909ms.
[15:33:23.454] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:23.454] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:33:23.454] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:33:23.729] INFO: PixTestAlive::aliveTest() done
[15:33:23.729] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[15:33:23.729] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 24 20 36 38 61 54 58 58 53 58 57 49 58 39 16 32
[15:33:23.756] INFO: ----------------------------------------------------------------------
[15:33:23.756] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:33:23.756] INFO: ----------------------------------------------------------------------
[15:33:23.760] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:33:24.138] INFO: Expecting 41600 events.
[15:33:27.248] INFO: 41600 events read in total (2395ms).
[15:33:27.248] INFO: Test took 3488ms.
[15:33:27.248] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:27.248] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:33:27.248] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:33:27.249] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:33:27.671] INFO: PixTestAlive::maskTest() done
[15:33:27.671] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:33:27.694] INFO: ----------------------------------------------------------------------
[15:33:27.694] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:33:27.694] INFO: ----------------------------------------------------------------------
[15:33:27.696] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:33:28.075] INFO: Expecting 41600 events.
[15:33:32.487] INFO: 41600 events read in total (3697ms).
[15:33:32.488] INFO: Test took 4792ms.
[15:33:32.499] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:32.499] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:33:32.499] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:33:32.871] INFO: PixTestAlive::addressDecodingTest() done
[15:33:32.871] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:33:32.871] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:33:37.843] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:33:37.843] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[15:33:43.787] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:33:43.787] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:33:43.787] INFO: ----------------------------------------------------------------------
[15:33:43.787] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:33:43.787] INFO: ----------------------------------------------------------------------
[15:33:43.926] INFO: Expecting 768 events.
[15:33:45.061] INFO: 768 events read in total (419ms).
[15:33:45.061] INFO: Test took 1268ms.
[15:33:45.067] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:33:45.864] INFO: Expecting 41600 events.
[15:33:49.482] INFO: 41600 events read in total (3091ms).
[15:33:49.487] INFO: Test took 4420ms.
[15:33:49.601] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:49.601] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 647344
[15:33:49.601] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:33:49.601] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:33:49.650] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:33:50.316] INFO: Expecting 41600 events.
[15:33:54.037] INFO: 41600 events read in total (3195ms).
[15:33:54.042] INFO: Test took 4391ms.
[15:33:54.153] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:54.153] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 654262
[15:33:54.153] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:33:54.153] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:33:54.204] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:33:54.870] INFO: Expecting 41600 events.
[15:33:58.619] INFO: 41600 events read in total (3222ms).
[15:33:58.623] INFO: Test took 4418ms.
[15:33:58.734] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:58.734] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 658791
[15:33:58.734] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:33:58.734] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:33:58.785] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:33:59.450] INFO: Expecting 41600 events.
[15:34:03.196] INFO: 41600 events read in total (3219ms).
[15:34:03.200] INFO: Test took 4415ms.
[15:34:03.315] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:03.315] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 657584
[15:34:03.315] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:34:03.315] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:34:03.366] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:34:04.033] INFO: Expecting 41600 events.
[15:34:07.787] INFO: 41600 events read in total (3228ms).
[15:34:07.791] INFO: Test took 4425ms.
[15:34:07.904] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:07.904] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 658017
[15:34:07.904] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:34:07.904] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:34:07.956] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:34:08.621] INFO: Expecting 41600 events.
[15:34:12.376] INFO: 41600 events read in total (3228ms).
[15:34:12.380] INFO: Test took 4424ms.
[15:34:12.489] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:12.489] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 659240
[15:34:12.489] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:34:12.490] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:34:12.542] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:34:13.207] INFO: Expecting 41600 events.
[15:34:16.977] INFO: 41600 events read in total (3243ms).
[15:34:16.981] INFO: Test took 4439ms.
[15:34:17.091] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:17.091] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 659021
[15:34:17.091] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:34:17.092] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:34:17.143] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:34:17.808] INFO: Expecting 41600 events.
[15:34:21.588] INFO: 41600 events read in total (3253ms).
[15:34:21.592] INFO: Test took 4449ms.
[15:34:21.703] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:21.703] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 659473
[15:34:21.703] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:34:21.704] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:34:21.755] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:34:22.420] INFO: Expecting 41600 events.
[15:34:26.199] INFO: 41600 events read in total (3252ms).
[15:34:26.203] INFO: Test took 4448ms.
[15:34:26.317] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:26.317] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 659345
[15:34:26.317] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:34:26.317] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:34:26.368] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:34:27.033] INFO: Expecting 41600 events.
[15:34:30.809] INFO: 41600 events read in total (3249ms).
[15:34:30.814] INFO: Test took 4446ms.
[15:34:30.924] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:30.924] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 658742
[15:34:30.924] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:34:30.925] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:34:30.976] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:34:31.641] INFO: Expecting 41600 events.
[15:34:35.421] INFO: 41600 events read in total (3253ms).
[15:34:35.426] INFO: Test took 4450ms.
[15:34:35.539] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:35.539] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 659443
[15:34:35.539] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:34:35.539] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:34:35.592] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:34:36.258] INFO: Expecting 41600 events.
[15:34:40.033] INFO: 41600 events read in total (3249ms).
[15:34:40.037] INFO: Test took 4445ms.
[15:34:40.149] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:40.149] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 659078
[15:34:40.149] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:34:40.150] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:34:40.202] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:34:40.870] INFO: Expecting 41600 events.
[15:34:44.640] INFO: 41600 events read in total (3243ms).
[15:34:44.644] INFO: Test took 4442ms.
[15:34:44.757] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:44.757] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 657888
[15:34:44.757] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:34:44.758] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:34:44.810] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:34:45.476] INFO: Expecting 41600 events.
[15:34:49.257] INFO: 41600 events read in total (3254ms).
[15:34:49.262] INFO: Test took 4452ms.
[15:34:49.374] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:49.374] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 657782
[15:34:49.374] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:34:49.374] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:34:49.425] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:34:50.090] INFO: Expecting 41600 events.
[15:34:53.864] INFO: 41600 events read in total (3247ms).
[15:34:53.869] INFO: Test took 4444ms.
[15:34:53.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:53.979] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 658333
[15:34:53.979] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:34:53.979] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:34:54.030] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:34:54.695] INFO: Expecting 41600 events.
[15:34:58.470] INFO: 41600 events read in total (3248ms).
[15:34:58.475] INFO: Test took 4445ms.
[15:34:58.585] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:58.585] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 657898
[15:34:58.585] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:34:58.586] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:34:58.637] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:34:59.302] INFO: Expecting 41600 events.
[15:35:03.082] INFO: 41600 events read in total (3253ms).
[15:35:03.087] INFO: Test took 4450ms.
[15:35:03.197] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:03.197] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 658021
[15:35:03.197] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:35:03.197] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:35:03.249] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:35:03.914] INFO: Expecting 41600 events.
[15:35:07.686] INFO: 41600 events read in total (3245ms).
[15:35:07.690] INFO: Test took 4441ms.
[15:35:07.802] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:07.802] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 656049
[15:35:07.802] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:35:07.802] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:35:07.853] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:35:08.518] INFO: Expecting 41600 events.
[15:35:12.263] INFO: 41600 events read in total (3219ms).
[15:35:12.267] INFO: Test took 4414ms.
[15:35:12.378] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:12.379] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 656511
[15:35:12.379] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:35:12.379] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:35:12.430] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:35:13.095] INFO: Expecting 41600 events.
[15:35:16.722] INFO: 41600 events read in total (3100ms).
[15:35:16.727] INFO: Test took 4297ms.
[15:35:16.835] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:16.835] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 652731
[15:35:16.835] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:35:16.836] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:35:17.109] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 144 eff = 0.999255
[15:35:17.110] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 142 eff = 0.999543
[15:35:17.110] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 154 eff = 0.998822
[15:35:17.110] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 184 eff = 0.998149
[15:35:17.110] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 197 eff = 0.997981
[15:35:17.110] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 145 eff = 0.997837
[15:35:17.110] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 152 eff = 0.998149
[15:35:17.111] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 164 eff = 0.960577
[15:35:17.111] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 158 eff = 0.998173
[15:35:17.111] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 197 eff = 0.997861
[15:35:17.111] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 144 eff = 0.997668
[15:35:17.111] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 160 eff = 0.997909
[15:35:17.111] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 205 eff = 0.997861
[15:35:17.111] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 145 eff = 0.998918
[15:35:17.112] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 139 eff = 0.999615
[15:35:17.112] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 139 eff = 0.999303
[15:35:32.371] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:35:32.371] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:35:32.371] INFO: ----------------------------------------------------------------------
[15:35:32.371] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:35:32.371] INFO: ----------------------------------------------------------------------
[15:35:32.371] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:35:32.371] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:35:32.371] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:35:32.371] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:35:32.371] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:35:32.371] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:35:32.372] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:35:32.372] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:35:32.372] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:35:32.372] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:35:32.372] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:35:32.372] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:35:32.389] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:35:33.057] INFO: Expecting 208000 events.
[15:35:47.963] INFO: 208000 events read in total (14379ms).
[15:35:47.979] INFO: Test took 15590ms.
[15:35:48.503] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:48.503] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2995900
[15:35:48.503] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:35:48.503] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:35:48.935] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 82 0 0 1 0 0 0 0 0
[15:35:48.935] INFO: number of red-efficiency pixels: 231 135 312 441 416 432 454 566 393 535 471 422 428 265 133 146
[15:35:48.935] INFO: number of X-ray hits detected: 137698 87238 150120 214655 226585 225635 227600 204734 204774 234725 228656 219369 225760 148237 83126 110511
[15:35:48.935] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:35:48.935] INFO: number of Vcal hits detected: 207758 207860 207657 207517 207529 207537 207514 199669 207578 207403 207449 207540 207536 207717 207863 207849
[15:35:48.935] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 97.8 99.8 99.7 99.8 99.8 99.8 99.9 99.9 99.9
[15:35:48.935] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.8 99.8 96.0 99.8 99.7 99.7 99.8 99.8 99.9 99.9 99.9
[15:35:48.935] INFO: X-ray hit rate [MHz/cm2]: 40.4 25.6 44.0 62.9 66.4 66.1 66.7 60.0 60.0 68.8 67.0 64.3 66.2 43.4 24.4 32.4
[15:35:48.935] INFO: PixTestHighRate::doXPixelAlive() done
[15:35:48.982] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:35:48.982] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:35:48.982] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:35:48.982] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:35:48.982] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:35:48.982] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:35:48.982] INFO: PixTest:: pg_setup set to default.
[15:35:53.123] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:35:53.123] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[15:35:55.283] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[15:35:55.283] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[15:35:55.283] INFO: ----------------------------------------------------------------------
[15:35:55.283] INFO: PixTestHighRate::runDaq() running for 2 seconds
[15:35:55.283] INFO: ----------------------------------------------------------------------
[15:35:55.486] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[15:35:55.486] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[15:35:55.486] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[15:35:55.486] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[15:35:55.486] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[15:35:55.486] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[15:35:55.486] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[15:35:55.486] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[15:35:55.486] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[15:35:55.486] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[15:35:55.486] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[15:35:55.486] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[15:35:56.443] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[15:35:58.446] INFO: data taking finished, elapsed time: 2 seconds.
[15:36:00.156] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39825 events with 556782 pixels
[15:36:00.162] INFO: PixTest:: pg_setup set to default.
[15:36:00.285] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[15:36:00.286] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[15:36:00.286] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[15:36:00.286] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[15:36:00.286] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[15:36:00.286] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[15:36:00.287] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[15:36:00.287] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[15:36:00.287] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[15:36:00.287] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[15:36:00.287] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[15:36:00.287] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[15:36:00.287] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[15:36:00.288] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[15:36:00.288] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[15:36:00.288] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[15:36:00.288] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[15:36:00.288] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[15:36:00.288] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[15:36:00.288] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[15:36:00.288] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[15:36:00.288] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[15:36:00.288] INFO: Pixels without X-ray hits (per ROC): 433 1088 557 126 120 113 122 137 189 108 130 214 99 560 1128 506
[15:36:00.288] INFO: PixTestHighRate::doRunDaq() done
[15:36:15.171] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:36:15.171] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C13_V0 -> hitMap_daqbbtest_mod
[15:36:19.019] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:36:19.020] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 388.3mA
[15:36:19.020] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[15:36:19.020] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[15:36:19.020] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:36:19.021] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:36:19.021] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:36:19.021] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:36:19.021] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:36:19.021] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:36:19.021] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:36:19.021] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:36:19.021] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:36:19.021] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:36:19.126] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:36:19.127] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:36:19.140] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:36:19.140] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:36:19.140] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:36:19.140] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:36:19.140] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:36:19.140] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:36:19.143] QUIET: Connection to board 58 closed.
[15:36:19.223] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[15:30:12.169] INFO: *** Welcome to pxar ***
[15:30:12.169] INFO: *** Today: 2016/10/04
[15:30:12.192] INFO: *** Version: v1.9.0-818-g96727
[15:30:12.192] INFO: readRocDacs: data/mq634/dacParameters35_C0.dat .. data/mq634/dacParameters35_C15.dat
[15:30:12.193] INFO: readTbmDacs: data/mq634/tbmParameters_C0a.dat .. data/mq634/tbmParameters_C0b.dat
[15:30:12.194] INFO: readMaskFile: data/mq634/defaultMaskFile.dat
[15:30:12.194] INFO: readTrimFile: data/mq634/trimParameters35_C0.dat .. data/mq634/trimParameters35_C15.dat
[15:30:12.342] INFO: clk: 4
[15:30:12.342] INFO: ctr: 4
[15:30:12.342] INFO: sda: 19
[15:30:12.342] INFO: tin: 9
[15:30:12.342] INFO: level: 15
[15:30:12.342] INFO: triggerdelay: 0
[15:30:12.342] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:30:12.342] INFO: Log level: DEBUG
[15:30:12.354] QUIET: Connection to board DTB_WRPRHI opened.
[15:30:12.357] 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:
------------------------------------------------------
[15:30:12.360] INFO: RPC call hashes of host and DTB match: 398089610
[15:30:13.964] INFO: DUT info:
[15:30:13.964] INFO: The DUT currently contains the following objects:
[15:30:13.964] INFO: 2 TBM Cores tbm08c (2 ON)
[15:30:13.964] INFO: TBM Core alpha (0): 7 registers set
[15:30:13.964] INFO: TBM Core beta (1): 7 registers set
[15:30:13.964] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:30:13.964] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.964] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.964] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.964] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.964] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.964] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.964] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.964] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.964] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.964] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.964] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.964] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.964] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.964] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.964] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.964] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:30:13.965] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:30:13.966] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:30:13.967] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:30:13.968] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:30:13.968] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:30:13.968] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:30:13.968] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:30:13.968] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:30:13.968] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:30:13.970] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34881536
[15:30:13.970] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2a360e0
[15:30:13.970] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x27d8370
[15:30:13.970] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f0011d94010
[15:30:13.970] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f0017fff510
[15:30:13.970] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34889728 fPxarMemory = 0x7f0011d94010
[15:30:13.971] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[15:30:13.972] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[15:30:13.972] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[15:30:14.470] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2e24fd0
[15:30:14.536] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:30:14.536] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:30:14.536] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:30:14.542] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:30:14.542] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:30:14.542] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:30:14.554] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:30:14.554] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:30:14.563] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:30:14.563] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:30:14.588] INFO: PixTestCmd::init()
[15:30:14.603] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:30:14.603] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:30:14.603] INFO: readGainPedestalParameters data/mq634/phCalibrationFitErr35_C0.dat .. data/mq634/phCalibrationFitErr35_C15.dat
[15:30:14.850] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:30:14.850] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:30:14.863] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:30:14.869] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:30:14.869] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:30:14.869] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:30:14.892] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:30:14.892] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:30:14.892] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:30:14.893] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:30:14.893] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:30:14.899] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:30:14.899] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:30:14.914] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:30:14.914] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:30:14.915] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:30:14.915] INFO: readReadbackCal: data/mq634/readbackCal_C0.dat .. data/mq634/readbackCal_C15.dat
[15:30:14.924] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:30:14.932] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:30:14.932] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:30:14.932] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:30:14.932] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:30:14.950] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:30:14.950] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:30:18.494] INFO: ######################################################################
[15:30:18.494] INFO: PixTestAlive::doTest()
[15:30:18.494] INFO: ######################################################################
[15:30:18.498] INFO: ----------------------------------------------------------------------
[15:30:18.498] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:30:18.498] INFO: ----------------------------------------------------------------------
[15:30:18.500] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:30:18.878] INFO: Expecting 41600 events.
[15:30:23.274] INFO: 41600 events read in total (3678ms).
[15:30:23.420] INFO: Test took 4920ms.
[15:30:23.432] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:23.432] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:30:23.432] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:30:23.707] INFO: PixTestAlive::aliveTest() done
[15:30:23.707] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[15:30:23.707] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 37 19 55 82 70 68 86 75 68 72 77 71 63 57 34 38
[15:30:23.731] INFO: ----------------------------------------------------------------------
[15:30:23.731] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:30:23.731] INFO: ----------------------------------------------------------------------
[15:30:23.734] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:30:24.112] INFO: Expecting 41600 events.
[15:30:27.222] INFO: 41600 events read in total (2395ms).
[15:30:27.223] INFO: Test took 3489ms.
[15:30:27.223] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:27.223] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:30:27.223] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:30:27.223] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:30:27.645] INFO: PixTestAlive::maskTest() done
[15:30:27.645] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:30:27.667] INFO: ----------------------------------------------------------------------
[15:30:27.667] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:30:27.667] INFO: ----------------------------------------------------------------------
[15:30:27.670] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:30:28.046] INFO: Expecting 41600 events.
[15:30:32.435] INFO: 41600 events read in total (3673ms).
[15:30:32.436] INFO: Test took 4766ms.
[15:30:32.447] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:32.447] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:30:32.447] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:30:32.818] INFO: PixTestAlive::addressDecodingTest() done
[15:30:32.818] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:30:32.818] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:30:36.370] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:30:36.370] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[15:30:39.907] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:30:39.907] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:30:39.907] INFO: ----------------------------------------------------------------------
[15:30:39.907] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:30:39.907] INFO: ----------------------------------------------------------------------
[15:30:40.046] INFO: Expecting 768 events.
[15:30:41.180] INFO: 768 events read in total (419ms).
[15:30:41.180] INFO: Test took 1267ms.
[15:30:41.186] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:30:41.985] INFO: Expecting 41600 events.
[15:30:45.805] INFO: 41600 events read in total (3294ms).
[15:30:45.810] INFO: Test took 4625ms.
[15:30:45.960] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:45.960] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 853890
[15:30:45.960] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:30:45.960] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:30:46.024] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:30:46.692] INFO: Expecting 41600 events.
[15:30:50.628] INFO: 41600 events read in total (3410ms).
[15:30:50.633] INFO: Test took 4609ms.
[15:30:50.772] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:50.772] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 861057
[15:30:50.772] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:30:50.772] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:30:50.838] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:30:51.503] INFO: Expecting 41600 events.
[15:30:55.477] INFO: 41600 events read in total (3447ms).
[15:30:55.482] INFO: Test took 4644ms.
[15:30:55.623] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:55.623] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864308
[15:30:55.623] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:30:55.623] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:30:55.689] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:30:56.354] INFO: Expecting 41600 events.
[15:31:00.337] INFO: 41600 events read in total (3456ms).
[15:31:00.342] INFO: Test took 4653ms.
[15:31:00.483] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:00.483] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864614
[15:31:00.483] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:31:00.483] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:31:00.549] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:31:01.215] INFO: Expecting 41600 events.
[15:31:05.196] INFO: 41600 events read in total (3455ms).
[15:31:05.201] INFO: Test took 4652ms.
[15:31:05.341] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:05.341] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 863667
[15:31:05.341] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:31:05.341] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:31:05.406] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:31:06.071] INFO: Expecting 41600 events.
[15:31:10.029] INFO: 41600 events read in total (3431ms).
[15:31:10.035] INFO: Test took 4629ms.
[15:31:10.175] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:10.175] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 863078
[15:31:10.175] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:31:10.176] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:31:10.242] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:31:10.907] INFO: Expecting 41600 events.
[15:31:14.917] INFO: 41600 events read in total (3484ms).
[15:31:14.922] INFO: Test took 4680ms.
[15:31:15.062] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:15.062] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 865164
[15:31:15.062] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:31:15.062] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:31:15.127] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:31:15.792] INFO: Expecting 41600 events.
[15:31:19.786] INFO: 41600 events read in total (3467ms).
[15:31:19.791] INFO: Test took 4664ms.
[15:31:19.930] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:19.930] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 865914
[15:31:19.930] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:31:19.930] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:31:19.996] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:31:20.661] INFO: Expecting 41600 events.
[15:31:24.647] INFO: 41600 events read in total (3460ms).
[15:31:24.652] INFO: Test took 4656ms.
[15:31:24.794] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:24.794] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 865900
[15:31:24.794] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:31:24.794] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:31:24.860] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:31:25.526] INFO: Expecting 41600 events.
[15:31:29.539] INFO: 41600 events read in total (3486ms).
[15:31:29.544] INFO: Test took 4684ms.
[15:31:29.684] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:29.684] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 865458
[15:31:29.684] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:31:29.685] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:31:29.751] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:31:30.417] INFO: Expecting 41600 events.
[15:31:34.438] INFO: 41600 events read in total (3494ms).
[15:31:34.444] INFO: Test took 4692ms.
[15:31:34.586] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:34.586] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 865074
[15:31:34.586] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:31:34.586] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:31:34.654] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:31:35.320] INFO: Expecting 41600 events.
[15:31:39.338] INFO: 41600 events read in total (3491ms).
[15:31:39.343] INFO: Test took 4689ms.
[15:31:39.485] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:39.485] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 865446
[15:31:39.485] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:31:39.486] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:31:39.554] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:31:40.220] INFO: Expecting 41600 events.
[15:31:44.243] INFO: 41600 events read in total (3496ms).
[15:31:44.249] INFO: Test took 4695ms.
[15:31:44.391] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:44.391] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 868272
[15:31:44.391] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:31:44.391] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:31:44.458] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:31:45.124] INFO: Expecting 41600 events.
[15:31:49.118] INFO: 41600 events read in total (3468ms).
[15:31:49.123] INFO: Test took 4665ms.
[15:31:49.267] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:49.267] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864763
[15:31:49.267] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:31:49.267] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:31:49.333] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:31:49.999] INFO: Expecting 41600 events.
[15:31:54.020] INFO: 41600 events read in total (3495ms).
[15:31:54.025] INFO: Test took 4691ms.
[15:31:54.165] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:54.165] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864189
[15:31:54.165] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:31:54.165] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:31:54.232] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:31:54.897] INFO: Expecting 41600 events.
[15:31:58.894] INFO: 41600 events read in total (3470ms).
[15:31:58.899] INFO: Test took 4667ms.
[15:31:59.040] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:59.040] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864206
[15:31:59.041] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:31:59.041] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:31:59.107] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:31:59.773] INFO: Expecting 41600 events.
[15:32:03.790] INFO: 41600 events read in total (3490ms).
[15:32:03.795] INFO: Test took 4688ms.
[15:32:03.937] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:03.937] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 867235
[15:32:03.937] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:32:03.937] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:32:04.004] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:32:04.669] INFO: Expecting 41600 events.
[15:32:08.680] INFO: 41600 events read in total (3484ms).
[15:32:08.685] INFO: Test took 4682ms.
[15:32:08.827] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:08.827] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864518
[15:32:08.827] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:32:08.827] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:32:08.894] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:32:09.559] INFO: Expecting 41600 events.
[15:32:13.538] INFO: 41600 events read in total (3453ms).
[15:32:13.543] INFO: Test took 4649ms.
[15:32:13.685] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:13.685] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 862709
[15:32:13.685] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:32:13.685] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:32:13.752] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:32:14.417] INFO: Expecting 41600 events.
[15:32:18.250] INFO: 41600 events read in total (3307ms).
[15:32:18.255] INFO: Test took 4503ms.
[15:32:18.394] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:18.395] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 856789
[15:32:18.395] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:32:18.395] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:32:18.683] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 153 eff = 0.998413
[15:32:18.683] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 164 eff = 0.999087
[15:32:18.684] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 169 eff = 0.997837
[15:32:18.684] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 190 eff = 0.99649
[15:32:18.684] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 191 eff = 0.995721
[15:32:18.684] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 145 eff = 0.995841
[15:32:18.685] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 152 eff = 0.995625
[15:32:18.685] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 164 eff = 0.958726
[15:32:18.685] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 159 eff = 0.996707
[15:32:18.685] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 208 eff = 0.995481
[15:32:18.686] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 144 eff = 0.995841
[15:32:18.686] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 163 eff = 0.996034
[15:32:18.686] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 205 eff = 0.996346
[15:32:18.686] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 148 eff = 0.9975
[15:32:18.686] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 143 eff = 0.999111
[15:32:18.687] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 138 eff = 0.999087
[15:32:22.843] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:32:22.843] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:32:22.843] INFO: ----------------------------------------------------------------------
[15:32:22.843] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:32:22.843] INFO: ----------------------------------------------------------------------
[15:32:22.843] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:32:22.843] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:32:22.843] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:32:22.843] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:32:22.844] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:32:22.844] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:32:22.844] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:32:22.844] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:32:22.844] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:32:22.844] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:32:22.844] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:32:22.844] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:32:22.862] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:32:23.532] INFO: Expecting 208000 events.
[15:32:40.175] INFO: 208000 events read in total (16117ms).
[15:32:40.195] INFO: Test took 17333ms.
[15:32:40.873] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:40.873] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4009462
[15:32:40.873] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:32:40.874] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:32:41.371] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 67 0 0 1 0 0 0 0 0
[15:32:41.371] INFO: number of red-efficiency pixels: 337 179 425 632 734 715 730 869 659 743 780 751 729 394 180 220
[15:32:41.371] INFO: number of X-ray hits detected: 186298 116981 201776 288947 305338 304139 305121 275117 274809 316189 307891 295485 304488 199486 111458 149447
[15:32:41.371] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:32:41.371] INFO: number of Vcal hits detected: 207641 207810 207522 207280 207161 207177 207172 199355 207255 207121 207037 207132 207150 207544 207811 207773
[15:32:41.371] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.6 99.6 99.6 97.3 99.7 99.6 99.6 99.6 99.6 99.8 99.9 99.9
[15:32:41.371] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.7 99.6 99.6 99.6 95.8 99.6 99.6 99.5 99.6 99.6 99.8 99.9 99.9
[15:32:41.371] INFO: X-ray hit rate [MHz/cm2]: 54.6 34.3 59.1 84.7 89.5 89.1 89.4 80.6 80.5 92.7 90.2 86.6 89.2 58.5 32.7 43.8
[15:32:41.371] INFO: PixTestHighRate::doXPixelAlive() done
[15:32:41.415] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:32:41.415] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:32:41.415] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:32:41.415] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:32:41.415] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:32:41.415] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:32:41.415] INFO: PixTest:: pg_setup set to default.
[15:32:44.915] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:32:44.915] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[15:32:49.363] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[15:32:49.363] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[15:32:49.363] INFO: ----------------------------------------------------------------------
[15:32:49.363] INFO: PixTestHighRate::runDaq() running for 2 seconds
[15:32:49.363] INFO: ----------------------------------------------------------------------
[15:32:49.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[15:32:49.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[15:32:49.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[15:32:49.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[15:32:49.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[15:32:49.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[15:32:49.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[15:32:49.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[15:32:49.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[15:32:49.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[15:32:49.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[15:32:49.561] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[15:32:50.518] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[15:32:52.521] INFO: data taking finished, elapsed time: 2 seconds.
[15:32:54.444] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39822 events with 751314 pixels
[15:32:54.449] INFO: PixTest:: pg_setup set to default.
[15:32:54.558] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[15:32:54.558] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[15:32:54.558] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[15:32:54.558] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[15:32:54.558] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[15:32:54.559] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[15:32:54.559] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[15:32:54.559] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[15:32:54.559] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[15:32:54.559] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[15:32:54.559] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[15:32:54.559] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[15:32:54.560] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[15:32:54.560] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[15:32:54.560] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[15:32:54.560] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[15:32:54.560] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[15:32:54.560] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[15:32:54.560] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[15:32:54.560] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[15:32:54.560] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[15:32:54.560] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[15:32:54.560] INFO: Pixels without X-ray hits (per ROC): 320 906 477 82 87 75 82 106 141 65 55 172 80 425 947 359
[15:32:54.560] INFO: PixTestHighRate::doRunDaq() done
[15:32:59.667] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:32:59.667] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C13_V0 -> hitMap_daqbbtest_mod
[15:33:01.747] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:33:01.748] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 388.3mA
[15:33:01.748] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[15:33:01.749] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:33:01.749] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:33:01.749] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:33:01.749] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:33:01.749] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:33:01.749] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:33:01.749] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:33:01.749] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:33:01.749] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:33:01.749] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:33:01.749] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:33:01.860] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:33:01.860] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:33:01.873] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:33:01.873] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:33:01.873] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:33:01.874] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:33:01.874] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:33:01.874] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:33:01.876] QUIET: Connection to board 58 closed.
[15:33:01.956] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-904-gd94b9f2 on branch master