Test Date: 1973-11-29 16:33
Analysis date: 2016-09-30 18:07
Logfile
hrData_40.log
[17:08:39.219] INFO: *** Welcome to pxar ***
[17:08:39.220] INFO: *** Today: 2016/09/29
[17:08:39.243] INFO: *** Version: v1.9.0-818-g96727
[17:08:39.243] INFO: readRocDacs: data/mq309/dacParameters35_C0.dat .. data/mq309/dacParameters35_C15.dat
[17:08:39.244] INFO: readTbmDacs: data/mq309/tbmParameters_C0a.dat .. data/mq309/tbmParameters_C0b.dat
[17:08:39.244] INFO: readMaskFile: data/mq309/defaultMaskFile.dat
[17:08:39.244] INFO: readTrimFile: data/mq309/trimParameters35_C0.dat .. data/mq309/trimParameters35_C15.dat
[17:08:39.394] INFO: clk: 4
[17:08:39.395] INFO: ctr: 4
[17:08:39.395] INFO: sda: 19
[17:08:39.395] INFO: tin: 9
[17:08:39.395] INFO: level: 15
[17:08:39.395] INFO: triggerdelay: 0
[17:08:39.395] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[17:08:39.395] INFO: Log level: DEBUG
[17:08:39.406] QUIET: Connection to board DTB_WRPRHI opened.
[17:08:39.410] 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:
------------------------------------------------------
[17:08:39.412] INFO: RPC call hashes of host and DTB match: 398089610
[17:08:41.015] INFO: DUT info:
[17:08:41.015] INFO: The DUT currently contains the following objects:
[17:08:41.015] INFO: 2 TBM Cores tbm08c (2 ON)
[17:08:41.015] INFO: TBM Core alpha (0): 7 registers set
[17:08:41.015] INFO: TBM Core beta (1): 7 registers set
[17:08:41.015] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:08:41.015] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.015] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.015] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.015] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.015] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.015] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.016] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.016] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.016] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.016] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.016] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.016] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.016] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.016] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.016] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.016] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:08:41.016] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:08:41.017] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:08:41.018] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:08:41.019] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:08:41.022] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34861056
[17:08:41.022] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xf900e0
[17:08:41.022] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xd32370
[17:08:41.022] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f577dd94010
[17:08:41.022] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f5783fff510
[17:08:41.022] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34869248 fPxarMemory = 0x7f577dd94010
[17:08:41.023] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[17:08:41.024] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[17:08:41.024] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[17:08:41.523] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x137f030
[17:08:41.584] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:08:41.584] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:08:41.585] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:08:41.590] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:08:41.590] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:08:41.590] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:08:41.602] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:08:41.602] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:08:41.612] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:08:41.613] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:08:41.624] INFO: PixTestCmd::init()
[17:08:41.646] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:08:41.646] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:08:41.646] INFO: readGainPedestalParameters data/mq309/phCalibrationFitErr35_C0.dat .. data/mq309/phCalibrationFitErr35_C15.dat
[17:08:41.897] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:08:41.897] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:08:41.910] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:08:41.916] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:08:41.916] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[17:08:41.916] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:08:41.937] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:08:41.937] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:08:41.937] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:08:41.937] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:08:41.937] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:08:41.943] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:08:41.943] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:08:41.958] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:08:41.958] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:08:41.958] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:08:41.958] INFO: readReadbackCal: data/mq309/readbackCal_C0.dat .. data/mq309/readbackCal_C15.dat
[17:08:41.969] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:08:41.977] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[17:08:41.977] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[17:08:41.977] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[17:08:41.977] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[17:08:41.990] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:08:41.990] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:08:46.516] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[17:08:48.140] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[17:08:48.140] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[17:08:48.140] INFO: ----------------------------------------------------------------------
[17:08:48.140] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[17:08:48.140] INFO: ----------------------------------------------------------------------
[17:08:49.114] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[17:09:01.693] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[17:09:01.696] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:09:29.183] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1239317 events.
[17:09:35.068] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1239317, pixels seen in all events: 5380153
[17:09:35.238] INFO: Resuming triggers.
[17:09:47.832] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[17:09:47.869] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:10:15.302] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1240647 events.
[17:10:21.247] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1240647, pixels seen in all events: 5363618
[17:10:21.418] INFO: Resuming triggers.
[17:10:34.003] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[17:10:34.040] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:11:01.587] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1239796 events.
[17:11:07.534] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1239796, pixels seen in all events: 5373435
[17:11:07.702] INFO: Resuming triggers.
[17:11:20.290] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[17:11:20.326] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:11:47.933] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1240200 events.
[17:11:53.816] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1240200, pixels seen in all events: 5368737
[17:11:54.054] INFO: Resuming triggers.
[17:12:03.782] INFO: data taking finished, elapsed time: 60 seconds.
[17:12:03.978] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:12:25.161] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 958346 events.
[17:12:29.692] DEBUG: <PixTestXray.cc/processData:L823> # events read: 958346, pixels seen in all events: 4140659
[17:12:29.778] INFO: PixTest:: pg_setup set to default.
[17:12:29.868] INFO: PixTestXray::doPhRun() done
[17:12:36.484] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:12:36.485] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 381.9mA
[17:12:36.485] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[17:12:36.486] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[17:12:36.486] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:12:36.486] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:12:36.486] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:12:36.486] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:12:36.486] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:12:36.486] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:12:36.486] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:12:36.486] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:12:36.486] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:12:36.486] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[17:12:36.486] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:12:36.486] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:12:36.486] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:12:36.486] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:12:36.487] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:12:36.487] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[17:12:36.487] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:12:36.487] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:12:36.761] QUIET: Connection to board 58 closed.
[17:12:36.840] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[17:12:48.366] INFO: *** Welcome to pxar ***
[17:12:48.366] INFO: *** Today: 2016/09/29
[17:12:48.389] INFO: *** Version: v1.9.0-818-g96727
[17:12:48.390] INFO: readRocDacs: data/mq309/dacParameters35_C0.dat .. data/mq309/dacParameters35_C15.dat
[17:12:48.391] INFO: readTbmDacs: data/mq309/tbmParameters_C0a.dat .. data/mq309/tbmParameters_C0b.dat
[17:12:48.391] INFO: readMaskFile: data/mq309/defaultMaskFile.dat
[17:12:48.391] INFO: readTrimFile: data/mq309/trimParameters35_C0.dat .. data/mq309/trimParameters35_C15.dat
[17:12:48.541] INFO: clk: 4
[17:12:48.541] INFO: ctr: 4
[17:12:48.542] INFO: sda: 19
[17:12:48.542] INFO: tin: 9
[17:12:48.542] INFO: level: 15
[17:12:48.542] INFO: triggerdelay: 0
[17:12:48.542] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[17:12:48.542] INFO: Log level: DEBUG
[17:12:48.553] QUIET: Connection to board DTB_WRPRHI opened.
[17:12:48.556] 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:
------------------------------------------------------
[17:12:48.559] INFO: RPC call hashes of host and DTB match: 398089610
[17:12:50.163] INFO: DUT info:
[17:12:50.163] INFO: The DUT currently contains the following objects:
[17:12:50.163] INFO: 2 TBM Cores tbm08c (2 ON)
[17:12:50.163] INFO: TBM Core alpha (0): 7 registers set
[17:12:50.163] INFO: TBM Core beta (1): 7 registers set
[17:12:50.163] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:12:50.163] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.163] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.163] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.163] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.163] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.163] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.163] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.163] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.163] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.163] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.163] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.163] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.163] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.163] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.163] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.163] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:12:50.164] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:12:50.165] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:12:50.166] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:12:50.167] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:12:50.169] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34824192
[17:12:50.169] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x15300e0
[17:12:50.169] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x12d2370
[17:12:50.169] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f5991d94010
[17:12:50.169] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f5997fff510
[17:12:50.169] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34832384 fPxarMemory = 0x7f5991d94010
[17:12:50.170] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[17:12:50.172] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[17:12:50.172] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[17:12:50.671] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x191ef70
[17:12:50.735] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:12:50.735] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:12:50.735] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:12:50.741] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:12:50.741] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:12:50.741] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:12:50.753] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:12:50.753] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:12:50.763] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:12:50.763] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:12:50.779] INFO: PixTestCmd::init()
[17:12:50.784] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:12:50.784] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:12:50.784] INFO: readGainPedestalParameters data/mq309/phCalibrationFitErr35_C0.dat .. data/mq309/phCalibrationFitErr35_C15.dat
[17:12:51.037] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:12:51.037] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:12:51.051] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:12:51.057] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:12:51.057] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[17:12:51.057] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:12:51.080] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:12:51.080] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:12:51.080] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:12:51.080] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:12:51.080] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:12:51.088] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:12:51.088] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:12:51.111] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:12:51.111] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:12:51.111] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:12:51.111] INFO: readReadbackCal: data/mq309/readbackCal_C0.dat .. data/mq309/readbackCal_C15.dat
[17:12:51.121] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:12:51.129] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[17:12:51.129] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[17:12:51.129] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[17:12:51.129] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[17:12:51.145] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:12:51.145] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:13:00.172] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[17:13:02.964] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[17:13:02.964] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[17:13:02.964] INFO: ----------------------------------------------------------------------
[17:13:02.964] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[17:13:02.964] INFO: ----------------------------------------------------------------------
[17:13:03.938] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[17:13:11.604] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[17:13:11.608] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:13:36.711] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 755370 events.
[17:13:48.795] DEBUG: <PixTestXray.cc/processData:L823> # events read: 755370, pixels seen in all events: 11189374
[17:13:49.045] INFO: Resuming triggers.
[17:13:56.713] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[17:13:56.751] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:14:21.768] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 755381 events.
[17:14:33.804] DEBUG: <PixTestXray.cc/processData:L823> # events read: 755381, pixels seen in all events: 11186981
[17:14:33.969] INFO: Resuming triggers.
[17:14:41.633] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[17:14:41.726] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:15:06.778] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 755051 events.
[17:15:18.835] DEBUG: <PixTestXray.cc/processData:L823> # events read: 755051, pixels seen in all events: 11191638
[17:15:19.059] INFO: Resuming triggers.
[17:15:26.725] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[17:15:26.762] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:15:51.799] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 755232 events.
[17:16:03.842] DEBUG: <PixTestXray.cc/processData:L823> # events read: 755232, pixels seen in all events: 11189051
[17:16:04.068] INFO: Resuming triggers.
[17:16:11.743] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[17:16:11.780] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:16:36.830] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 756058 events.
[17:16:48.901] DEBUG: <PixTestXray.cc/processData:L823> # events read: 756058, pixels seen in all events: 11178854
[17:16:49.101] INFO: Resuming triggers.
[17:16:56.769] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[17:16:56.813] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:17:21.897] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 755466 events.
[17:17:33.978] DEBUG: <PixTestXray.cc/processData:L823> # events read: 755466, pixels seen in all events: 11186179
[17:17:34.200] INFO: Resuming triggers.
[17:17:41.871] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[17:17:41.906] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:18:06.944] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 755651 events.
[17:18:19.012] DEBUG: <PixTestXray.cc/processData:L823> # events read: 755651, pixels seen in all events: 11184570
[17:18:19.241] INFO: Resuming triggers.
[17:18:25.642] INFO: data taking finished, elapsed time: 60 seconds.
[17:18:25.838] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:18:46.740] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 630600 events.
[17:18:56.813] DEBUG: <PixTestXray.cc/processData:L823> # events read: 630600, pixels seen in all events: 9330615
[17:18:57.007] INFO: PixTest:: pg_setup set to default.
[17:18:57.048] INFO: PixTestXray::doPhRun() done
[17:19:21.708] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:19:21.709] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 381.9mA
[17:19:21.710] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[17:19:21.710] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[17:19:21.710] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:19:21.710] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:19:21.710] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:19:21.710] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:19:21.710] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:19:21.710] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:19:21.710] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:19:21.710] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:19:21.710] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:19:21.711] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[17:19:21.711] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:19:21.711] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:19:21.711] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:19:21.711] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:19:21.711] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:19:21.711] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[17:19:21.711] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:19:21.711] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:19:22.021] QUIET: Connection to board 58 closed.
[17:19:22.101] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[17:03:15.210] INFO: *** Welcome to pxar ***
[17:03:15.210] INFO: *** Today: 2016/09/29
[17:03:15.233] INFO: *** Version: v1.9.0-818-g96727
[17:03:15.233] INFO: readRocDacs: data/mq309/dacParameters35_C0.dat .. data/mq309/dacParameters35_C15.dat
[17:03:15.234] INFO: readTbmDacs: data/mq309/tbmParameters_C0a.dat .. data/mq309/tbmParameters_C0b.dat
[17:03:15.234] INFO: readMaskFile: data/mq309/defaultMaskFile.dat
[17:03:15.234] INFO: readTrimFile: data/mq309/trimParameters35_C0.dat .. data/mq309/trimParameters35_C15.dat
[17:03:15.383] INFO: clk: 4
[17:03:15.383] INFO: ctr: 4
[17:03:15.383] INFO: sda: 19
[17:03:15.383] INFO: tin: 9
[17:03:15.383] INFO: level: 15
[17:03:15.383] INFO: triggerdelay: 0
[17:03:15.383] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[17:03:15.383] INFO: Log level: DEBUG
[17:03:15.397] QUIET: Connection to board DTB_WRPRHI opened.
[17:03:15.400] 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:
------------------------------------------------------
[17:03:15.402] INFO: RPC call hashes of host and DTB match: 398089610
[17:03:17.005] INFO: DUT info:
[17:03:17.005] INFO: The DUT currently contains the following objects:
[17:03:17.005] INFO: 2 TBM Cores tbm08c (2 ON)
[17:03:17.005] INFO: TBM Core alpha (0): 7 registers set
[17:03:17.005] INFO: TBM Core beta (1): 7 registers set
[17:03:17.005] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:03:17.005] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.005] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.005] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.005] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.005] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.005] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.005] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.005] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.005] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.005] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.005] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.006] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.006] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.006] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.006] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.006] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:03:17.006] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:03:17.007] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:03:17.008] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:03:17.009] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:03:17.011] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33427456
[17:03:17.011] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x26910e0
[17:03:17.011] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2433370
[17:03:17.011] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f0449393010
[17:03:17.011] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f044f5fe510
[17:03:17.011] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33435648 fPxarMemory = 0x7f0449393010
[17:03:17.013] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[17:03:17.014] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[17:03:17.014] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[17:03:17.513] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2a7ff70
[17:03:17.575] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:03:17.575] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:03:17.575] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:03:17.581] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:03:17.581] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:03:17.581] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:03:17.593] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:03:17.593] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:03:17.601] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:03:17.601] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:03:17.621] INFO: PixTestCmd::init()
[17:03:17.632] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:03:17.632] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:03:17.632] INFO: readGainPedestalParameters data/mq309/phCalibrationFitErr35_C0.dat .. data/mq309/phCalibrationFitErr35_C15.dat
[17:03:17.881] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:03:17.881] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:03:17.900] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:03:17.909] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:03:17.909] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[17:03:17.909] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:03:17.935] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:03:17.935] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:03:17.935] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:03:17.935] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:03:17.935] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:03:17.945] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:03:17.945] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:03:17.965] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:03:17.965] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:03:17.965] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:03:17.965] INFO: readReadbackCal: data/mq309/readbackCal_C0.dat .. data/mq309/readbackCal_C15.dat
[17:03:17.979] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:03:17.987] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[17:03:17.987] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[17:03:17.987] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[17:03:17.987] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[17:03:18.001] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:03:18.001] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:03:28.047] INFO: ######################################################################
[17:03:28.047] INFO: PixTestAlive::doTest()
[17:03:28.047] INFO: ######################################################################
[17:03:28.051] INFO: ----------------------------------------------------------------------
[17:03:28.051] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:03:28.051] INFO: ----------------------------------------------------------------------
[17:03:28.053] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:03:28.433] INFO: Expecting 41600 events.
[17:03:32.827] INFO: 41600 events read in total (3676ms).
[17:03:32.974] INFO: Test took 4921ms.
[17:03:32.985] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:03:32.985] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[17:03:32.985] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[17:03:33.262] INFO: PixTestAlive::aliveTest() done
[17:03:33.262] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 2 0 1
[17:03:33.263] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 22 23 21 40 44 31 54 40 46 53 39 39 57 28 12 24
[17:03:33.290] INFO: ----------------------------------------------------------------------
[17:03:33.290] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:03:33.290] INFO: ----------------------------------------------------------------------
[17:03:33.292] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:03:33.671] INFO: Expecting 41600 events.
[17:03:36.771] INFO: 41600 events read in total (2385ms).
[17:03:36.771] INFO: Test took 3478ms.
[17:03:36.771] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:03:36.771] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[17:03:36.771] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[17:03:36.772] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:03:37.195] INFO: PixTestAlive::maskTest() done
[17:03:37.195] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:03:37.217] INFO: ----------------------------------------------------------------------
[17:03:37.217] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:03:37.217] INFO: ----------------------------------------------------------------------
[17:03:37.219] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:03:37.598] INFO: Expecting 41600 events.
[17:03:41.996] INFO: 41600 events read in total (3682ms).
[17:03:41.998] INFO: Test took 4779ms.
[17:03:42.009] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:03:42.009] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[17:03:42.009] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[17:03:42.379] INFO: PixTestAlive::addressDecodingTest() done
[17:03:42.380] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:03:42.380] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[17:03:46.060] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[17:03:46.060] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[17:03:48.884] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[17:03:48.884] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[17:03:48.884] INFO: ----------------------------------------------------------------------
[17:03:48.884] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:03:48.884] INFO: ----------------------------------------------------------------------
[17:03:49.023] INFO: Expecting 768 events.
[17:03:50.156] INFO: 768 events read in total (418ms).
[17:03:50.157] INFO: Test took 1267ms.
[17:03:50.162] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:03:50.960] INFO: Expecting 41600 events.
[17:03:54.357] INFO: 41600 events read in total (2871ms).
[17:03:54.361] INFO: Test took 4199ms.
[17:03:54.442] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:03:54.442] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469599
[17:03:54.442] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[17:03:54.443] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:03:54.481] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:03:55.145] INFO: Expecting 41600 events.
[17:03:58.628] INFO: 41600 events read in total (2956ms).
[17:03:58.631] INFO: Test took 4150ms.
[17:03:58.711] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:03:58.711] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 475430
[17:03:58.711] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[17:03:58.711] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:03:58.751] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:03:59.417] INFO: Expecting 41600 events.
[17:04:02.926] INFO: 41600 events read in total (2982ms).
[17:04:02.930] INFO: Test took 4179ms.
[17:04:03.008] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:03.008] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 475816
[17:04:03.008] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[17:04:03.009] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:03.048] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:03.713] INFO: Expecting 41600 events.
[17:04:07.229] INFO: 41600 events read in total (2990ms).
[17:04:07.233] INFO: Test took 4185ms.
[17:04:07.312] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:07.312] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476319
[17:04:07.312] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[17:04:07.313] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:07.353] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:08.019] INFO: Expecting 41600 events.
[17:04:11.539] INFO: 41600 events read in total (2994ms).
[17:04:11.542] INFO: Test took 4189ms.
[17:04:11.621] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:11.621] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477558
[17:04:11.621] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[17:04:11.622] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:11.662] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:12.328] INFO: Expecting 41600 events.
[17:04:15.850] INFO: 41600 events read in total (2995ms).
[17:04:15.853] INFO: Test took 4192ms.
[17:04:15.933] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:15.933] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477557
[17:04:15.933] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[17:04:15.933] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:15.975] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:16.641] INFO: Expecting 41600 events.
[17:04:20.105] INFO: 41600 events read in total (2938ms).
[17:04:20.109] INFO: Test took 4133ms.
[17:04:20.187] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:20.187] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477709
[17:04:20.187] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[17:04:20.188] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:20.227] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:20.891] INFO: Expecting 41600 events.
[17:04:24.391] INFO: 41600 events read in total (2973ms).
[17:04:24.395] INFO: Test took 4168ms.
[17:04:24.473] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:24.473] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477959
[17:04:24.473] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[17:04:24.474] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:24.513] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:25.178] INFO: Expecting 41600 events.
[17:04:28.713] INFO: 41600 events read in total (3008ms).
[17:04:28.716] INFO: Test took 4204ms.
[17:04:28.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:28.795] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 478070
[17:04:28.795] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[17:04:28.795] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:28.835] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:29.500] INFO: Expecting 41600 events.
[17:04:33.032] INFO: 41600 events read in total (3005ms).
[17:04:33.036] INFO: Test took 4201ms.
[17:04:33.115] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:33.115] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 478547
[17:04:33.115] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[17:04:33.115] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:33.155] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:33.820] INFO: Expecting 41600 events.
[17:04:37.321] INFO: 41600 events read in total (2975ms).
[17:04:37.325] INFO: Test took 4170ms.
[17:04:37.404] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:37.404] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 478037
[17:04:37.404] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[17:04:37.404] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:37.444] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:38.109] INFO: Expecting 41600 events.
[17:04:41.640] INFO: 41600 events read in total (3005ms).
[17:04:41.643] INFO: Test took 4199ms.
[17:04:41.722] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:41.723] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477367
[17:04:41.723] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[17:04:41.723] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:41.762] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:42.427] INFO: Expecting 41600 events.
[17:04:45.957] INFO: 41600 events read in total (3003ms).
[17:04:45.960] INFO: Test took 4198ms.
[17:04:46.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:46.039] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477981
[17:04:46.039] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[17:04:46.039] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:46.079] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:46.744] INFO: Expecting 41600 events.
[17:04:50.218] INFO: 41600 events read in total (2948ms).
[17:04:50.222] INFO: Test took 4143ms.
[17:04:50.301] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:50.301] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477790
[17:04:50.301] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[17:04:50.301] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:50.341] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:51.006] INFO: Expecting 41600 events.
[17:04:54.511] INFO: 41600 events read in total (2978ms).
[17:04:54.515] INFO: Test took 4174ms.
[17:04:54.593] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:54.593] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 478179
[17:04:54.593] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[17:04:54.594] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:54.633] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:55.298] INFO: Expecting 41600 events.
[17:04:58.829] INFO: 41600 events read in total (3004ms).
[17:04:58.833] INFO: Test took 4199ms.
[17:04:58.911] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:58.911] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477706
[17:04:58.911] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[17:04:58.912] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:58.952] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:59.617] INFO: Expecting 41600 events.
[17:05:03.150] INFO: 41600 events read in total (3006ms).
[17:05:03.153] INFO: Test took 4201ms.
[17:05:03.232] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:03.232] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 478033
[17:05:03.232] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[17:05:03.233] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:05:03.272] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:05:03.937] INFO: Expecting 41600 events.
[17:05:07.454] INFO: 41600 events read in total (2990ms).
[17:05:07.458] INFO: Test took 4185ms.
[17:05:07.538] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:07.538] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477340
[17:05:07.538] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[17:05:07.538] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:05:07.578] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:05:08.244] INFO: Expecting 41600 events.
[17:05:11.740] INFO: 41600 events read in total (2970ms).
[17:05:11.743] INFO: Test took 4165ms.
[17:05:11.820] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:11.820] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476265
[17:05:11.820] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[17:05:11.821] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:05:11.861] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:05:12.526] INFO: Expecting 41600 events.
[17:05:15.901] INFO: 41600 events read in total (2849ms).
[17:05:15.905] INFO: Test took 4044ms.
[17:05:15.983] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:15.983] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 472260
[17:05:15.983] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[17:05:15.983] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:05:16.243] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 126 eff = 0.999567
[17:05:16.244] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 155 eff = 0.999808
[17:05:16.244] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 154 eff = 0.999543
[17:05:16.244] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 158 eff = 0.998822
[17:05:16.244] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 164 eff = 0.998942
[17:05:16.244] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 136 eff = 0.998918
[17:05:16.244] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 184 eff = 0.998894
[17:05:16.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 161 eff = 0.999014
[17:05:16.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 164 eff = 0.999255
[17:05:16.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 161 eff = 0.998894
[17:05:16.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 145 eff = 0.998846
[17:05:16.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 159 eff = 0.998918
[17:05:16.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 144 eff = 0.998534
[17:05:16.246] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 153 eff = 0.998942
[17:05:16.246] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 141 eff = 0.999712
[17:05:16.246] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 131 eff = 0.999471
[17:05:20.076] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[17:05:20.076] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[17:05:20.076] INFO: ----------------------------------------------------------------------
[17:05:20.076] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:05:20.076] INFO: ----------------------------------------------------------------------
[17:05:20.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[17:05:20.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[17:05:20.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[17:05:20.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[17:05:20.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[17:05:20.076] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[17:05:20.077] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[17:05:20.077] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[17:05:20.077] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[17:05:20.077] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[17:05:20.077] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[17:05:20.077] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[17:05:20.097] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:05:20.766] INFO: Expecting 208000 events.
[17:05:34.207] INFO: 208000 events read in total (12915ms).
[17:05:34.219] INFO: Test took 14122ms.
[17:05:34.592] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:34.592] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2113869
[17:05:34.592] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[17:05:34.593] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:05:34.967] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 2 0 1
[17:05:34.967] INFO: number of red-efficiency pixels: 129 82 173 210 276 276 299 212 237 272 292 246 247 149 95 119
[17:05:34.967] INFO: number of X-ray hits detected: 86973 58672 101969 150139 161623 155522 164668 144052 141281 164929 164113 152356 155495 102706 61557 81258
[17:05:34.967] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:05:34.967] INFO: number of Vcal hits detected: 207867 207915 207821 207782 207717 207713 207674 207779 207753 207695 207695 207743 207694 207752 207903 207828
[17:05:34.967] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[17:05:34.967] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[17:05:34.967] INFO: X-ray hit rate [MHz/cm2]: 25.5 17.2 29.9 44.0 47.4 45.6 48.3 42.2 41.4 48.3 48.1 44.7 45.6 30.1 18.0 23.8
[17:05:34.967] INFO: PixTestHighRate::doXPixelAlive() done
[17:05:35.013] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[17:05:35.013] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[17:05:35.013] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[17:05:35.013] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[17:05:35.013] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[17:05:35.013] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[17:05:35.013] INFO: PixTest:: pg_setup set to default.
[17:05:48.940] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[17:05:48.940] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[17:05:50.492] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[17:05:50.492] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[17:05:50.492] INFO: ----------------------------------------------------------------------
[17:05:50.492] INFO: PixTestHighRate::runDaq() running for 2 seconds
[17:05:50.492] INFO: ----------------------------------------------------------------------
[17:05:50.697] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[17:05:50.697] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[17:05:50.697] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[17:05:50.697] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[17:05:50.697] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[17:05:50.697] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[17:05:50.697] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[17:05:50.697] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[17:05:50.697] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[17:05:50.697] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[17:05:50.697] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[17:05:50.697] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[17:05:51.654] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[17:05:53.656] INFO: data taking finished, elapsed time: 2 seconds.
[17:05:55.170] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39817 events with 388500 pixels
[17:05:55.176] INFO: PixTest:: pg_setup set to default.
[17:05:55.315] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[17:05:55.315] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[17:05:55.315] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[17:05:55.315] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[17:05:55.315] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[17:05:55.316] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[17:05:55.316] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[17:05:55.316] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[17:05:55.316] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[17:05:55.316] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[17:05:55.316] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[17:05:55.316] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[17:05:55.317] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[17:05:55.317] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[17:05:55.317] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[17:05:55.317] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[17:05:55.317] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[17:05:55.317] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[17:05:55.317] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[17:05:55.317] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[17:05:55.317] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[17:05:55.317] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[17:05:55.317] INFO: Pixels without X-ray hits (per ROC): 753 1337 770 201 206 238 192 214 334 217 205 334 179 757 1312 851
[17:05:55.317] INFO: PixTestHighRate::doRunDaq() done
[17:06:02.028] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[17:06:02.028] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C13_V0 -> hitMap_daqbbtest_mod
[17:06:03.620] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:06:03.621] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 381.9mA
[17:06:03.621] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[17:06:03.621] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[17:06:03.621] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:06:03.622] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:06:03.622] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:06:03.622] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:06:03.622] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:06:03.622] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:06:03.622] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:06:03.622] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:06:03.622] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:06:03.622] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[17:06:03.729] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:06:03.729] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:06:03.743] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:06:03.743] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:06:03.743] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:06:03.743] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[17:06:03.743] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:06:03.743] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:06:03.746] QUIET: Connection to board 58 closed.
[17:06:03.825] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[17:00:03.072] INFO: *** Welcome to pxar ***
[17:00:03.072] INFO: *** Today: 2016/09/29
[17:00:03.097] INFO: *** Version: v1.9.0-818-g96727
[17:00:03.097] INFO: readRocDacs: data/mq309/dacParameters35_C0.dat .. data/mq309/dacParameters35_C15.dat
[17:00:03.098] INFO: readTbmDacs: data/mq309/tbmParameters_C0a.dat .. data/mq309/tbmParameters_C0b.dat
[17:00:03.098] INFO: readMaskFile: data/mq309/defaultMaskFile.dat
[17:00:03.098] INFO: readTrimFile: data/mq309/trimParameters35_C0.dat .. data/mq309/trimParameters35_C15.dat
[17:00:03.249] INFO: clk: 4
[17:00:03.249] INFO: ctr: 4
[17:00:03.249] INFO: sda: 19
[17:00:03.249] INFO: tin: 9
[17:00:03.249] INFO: level: 15
[17:00:03.249] INFO: triggerdelay: 0
[17:00:03.249] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[17:00:03.249] INFO: Log level: DEBUG
[17:00:03.261] QUIET: Connection to board DTB_WRPRHI opened.
[17:00:03.264] 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:
------------------------------------------------------
[17:00:03.267] INFO: RPC call hashes of host and DTB match: 398089610
[17:00:04.869] INFO: DUT info:
[17:00:04.869] INFO: The DUT currently contains the following objects:
[17:00:04.869] INFO: 2 TBM Cores tbm08c (2 ON)
[17:00:04.869] INFO: TBM Core alpha (0): 7 registers set
[17:00:04.869] INFO: TBM Core beta (1): 7 registers set
[17:00:04.869] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:00:04.869] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.869] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.869] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.869] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.869] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.869] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.870] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.870] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.870] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.870] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.870] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.870] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.870] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.870] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.870] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.870] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:00:04.870] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:00:04.871] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:00:04.872] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:00:04.873] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:00:04.875] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 36917248
[17:00:04.875] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x137d0e0
[17:00:04.875] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x111f370
[17:00:04.875] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f4ef9393010
[17:00:04.875] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f4eff5fe510
[17:00:04.875] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 36925440 fPxarMemory = 0x7f4ef9393010
[17:00:04.877] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[17:00:04.878] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[17:00:04.878] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[17:00:05.376] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x176beb0
[17:00:05.441] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:00:05.441] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:00:05.441] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:00:05.447] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:00:05.447] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:00:05.447] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:00:05.458] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:00:05.458] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:00:05.474] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:00:05.475] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:00:05.486] INFO: PixTestCmd::init()
[17:00:05.496] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:00:05.496] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:00:05.496] INFO: readGainPedestalParameters data/mq309/phCalibrationFitErr35_C0.dat .. data/mq309/phCalibrationFitErr35_C15.dat
[17:00:05.744] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:00:05.744] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:00:05.759] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:00:05.766] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:00:05.766] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[17:00:05.766] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:00:05.791] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:00:05.791] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:00:05.791] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:00:05.791] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:00:05.791] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:00:05.798] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:00:05.798] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:00:05.817] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:00:05.817] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:00:05.817] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:00:05.817] INFO: readReadbackCal: data/mq309/readbackCal_C0.dat .. data/mq309/readbackCal_C15.dat
[17:00:05.827] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:00:05.835] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[17:00:05.835] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[17:00:05.835] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[17:00:05.835] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[17:00:05.852] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:00:05.852] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:00:20.471] INFO: ######################################################################
[17:00:20.471] INFO: PixTestAlive::doTest()
[17:00:20.471] INFO: ######################################################################
[17:00:20.474] INFO: ----------------------------------------------------------------------
[17:00:20.475] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:00:20.475] INFO: ----------------------------------------------------------------------
[17:00:20.477] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:00:20.856] INFO: Expecting 41600 events.
[17:00:25.257] INFO: 41600 events read in total (3683ms).
[17:00:25.404] INFO: Test took 4927ms.
[17:00:25.415] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:25.415] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[17:00:25.415] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[17:00:25.690] INFO: PixTestAlive::aliveTest() done
[17:00:25.690] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 2 0 1
[17:00:25.690] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 37 22 43 58 76 55 62 56 60 69 68 50 69 37 27 37
[17:00:25.716] INFO: ----------------------------------------------------------------------
[17:00:25.716] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:00:25.716] INFO: ----------------------------------------------------------------------
[17:00:25.718] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:00:26.097] INFO: Expecting 41600 events.
[17:00:29.221] INFO: 41600 events read in total (2408ms).
[17:00:29.221] INFO: Test took 3503ms.
[17:00:29.221] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:29.221] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[17:00:29.221] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[17:00:29.222] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:00:29.644] INFO: PixTestAlive::maskTest() done
[17:00:29.644] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:00:29.666] INFO: ----------------------------------------------------------------------
[17:00:29.666] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:00:29.666] INFO: ----------------------------------------------------------------------
[17:00:29.668] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:00:30.046] INFO: Expecting 41600 events.
[17:00:34.434] INFO: 41600 events read in total (3673ms).
[17:00:34.435] INFO: Test took 4767ms.
[17:00:34.446] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:34.446] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[17:00:34.446] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[17:00:34.817] INFO: PixTestAlive::addressDecodingTest() done
[17:00:34.817] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:00:34.817] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[17:00:44.740] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[17:00:44.740] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[17:00:47.700] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[17:00:47.700] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[17:00:47.700] INFO: ----------------------------------------------------------------------
[17:00:47.700] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:00:47.700] INFO: ----------------------------------------------------------------------
[17:00:47.839] INFO: Expecting 768 events.
[17:00:48.973] INFO: 768 events read in total (419ms).
[17:00:48.973] INFO: Test took 1267ms.
[17:00:48.979] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:00:49.777] INFO: Expecting 41600 events.
[17:00:53.443] INFO: 41600 events read in total (3140ms).
[17:00:53.448] INFO: Test took 4469ms.
[17:00:53.570] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:53.570] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 689161
[17:00:53.570] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[17:00:53.570] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:00:53.623] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:00:54.289] INFO: Expecting 41600 events.
[17:00:58.027] INFO: 41600 events read in total (3212ms).
[17:00:58.031] INFO: Test took 4408ms.
[17:00:58.148] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:00:58.148] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 695599
[17:00:58.148] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[17:00:58.149] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:00:58.203] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:00:58.869] INFO: Expecting 41600 events.
[17:01:02.635] INFO: 41600 events read in total (3239ms).
[17:01:02.639] INFO: Test took 4436ms.
[17:01:02.755] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:02.755] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696029
[17:01:02.755] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[17:01:02.755] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:01:02.810] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:01:03.476] INFO: Expecting 41600 events.
[17:01:07.255] INFO: 41600 events read in total (3252ms).
[17:01:07.259] INFO: Test took 4449ms.
[17:01:07.375] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:07.376] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696992
[17:01:07.376] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[17:01:07.376] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:01:07.430] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:01:08.096] INFO: Expecting 41600 events.
[17:01:11.879] INFO: 41600 events read in total (3256ms).
[17:01:11.883] INFO: Test took 4453ms.
[17:01:12.000] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:12.000] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696694
[17:01:12.000] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[17:01:12.000] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:01:12.054] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:01:12.719] INFO: Expecting 41600 events.
[17:01:16.495] INFO: 41600 events read in total (3249ms).
[17:01:16.500] INFO: Test took 4446ms.
[17:01:16.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:16.617] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696781
[17:01:16.617] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[17:01:16.617] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:01:16.671] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:01:17.336] INFO: Expecting 41600 events.
[17:01:21.117] INFO: 41600 events read in total (3255ms).
[17:01:21.121] INFO: Test took 4450ms.
[17:01:21.240] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:21.240] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 697803
[17:01:21.240] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[17:01:21.240] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:01:21.295] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:01:21.959] INFO: Expecting 41600 events.
[17:01:25.764] INFO: 41600 events read in total (3278ms).
[17:01:25.768] INFO: Test took 4474ms.
[17:01:25.886] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:25.886] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696808
[17:01:25.886] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[17:01:25.886] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:01:25.940] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:01:26.607] INFO: Expecting 41600 events.
[17:01:30.412] INFO: 41600 events read in total (3278ms).
[17:01:30.416] INFO: Test took 4476ms.
[17:01:30.533] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:30.533] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 697375
[17:01:30.533] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[17:01:30.533] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:01:30.587] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:01:31.251] INFO: Expecting 41600 events.
[17:01:35.045] INFO: 41600 events read in total (3267ms).
[17:01:35.050] INFO: Test took 4463ms.
[17:01:35.163] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:35.163] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696704
[17:01:35.163] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[17:01:35.164] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:01:35.218] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:01:35.883] INFO: Expecting 41600 events.
[17:01:39.652] INFO: 41600 events read in total (3242ms).
[17:01:39.656] INFO: Test took 4438ms.
[17:01:39.773] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:39.773] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 697575
[17:01:39.773] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[17:01:39.774] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:01:39.827] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:01:40.492] INFO: Expecting 41600 events.
[17:01:44.285] INFO: 41600 events read in total (3266ms).
[17:01:44.289] INFO: Test took 4462ms.
[17:01:44.407] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:44.407] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 697571
[17:01:44.407] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[17:01:44.408] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:01:44.461] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:01:45.127] INFO: Expecting 41600 events.
[17:01:48.948] INFO: 41600 events read in total (3294ms).
[17:01:48.953] INFO: Test took 4492ms.
[17:01:49.070] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:49.070] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 697601
[17:01:49.070] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[17:01:49.070] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:01:49.124] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:01:49.789] INFO: Expecting 41600 events.
[17:01:53.597] INFO: 41600 events read in total (3281ms).
[17:01:53.601] INFO: Test took 4477ms.
[17:01:53.718] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:53.718] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696699
[17:01:53.718] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[17:01:53.718] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:01:53.773] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:01:54.438] INFO: Expecting 41600 events.
[17:01:58.242] INFO: 41600 events read in total (3277ms).
[17:01:58.246] INFO: Test took 4473ms.
[17:01:58.363] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:01:58.364] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 697531
[17:01:58.364] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[17:01:58.364] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:01:58.417] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:01:59.082] INFO: Expecting 41600 events.
[17:02:02.882] INFO: 41600 events read in total (3273ms).
[17:02:02.887] INFO: Test took 4470ms.
[17:02:03.004] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:02:03.004] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696949
[17:02:03.004] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[17:02:03.004] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:02:03.058] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:02:03.723] INFO: Expecting 41600 events.
[17:02:07.501] INFO: 41600 events read in total (3251ms).
[17:02:07.506] INFO: Test took 4448ms.
[17:02:07.624] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:02:07.624] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696832
[17:02:07.624] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[17:02:07.624] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:02:07.679] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:02:08.346] INFO: Expecting 41600 events.
[17:02:12.111] INFO: 41600 events read in total (3239ms).
[17:02:12.116] INFO: Test took 4437ms.
[17:02:12.233] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:02:12.233] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696357
[17:02:12.233] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[17:02:12.234] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:02:12.287] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:02:12.952] INFO: Expecting 41600 events.
[17:02:16.713] INFO: 41600 events read in total (3234ms).
[17:02:16.718] INFO: Test took 4431ms.
[17:02:16.836] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:02:16.836] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696247
[17:02:16.836] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[17:02:16.836] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:02:16.891] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:02:17.557] INFO: Expecting 41600 events.
[17:02:21.182] INFO: 41600 events read in total (3098ms).
[17:02:21.187] INFO: Test took 4296ms.
[17:02:21.300] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:02:21.300] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 689362
[17:02:21.300] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[17:02:21.301] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:02:21.576] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 147 eff = 0.999183
[17:02:21.576] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 156 eff = 0.999471
[17:02:21.576] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 160 eff = 0.998654
[17:02:21.576] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 155 eff = 0.99774
[17:02:21.577] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 164 eff = 0.9975
[17:02:21.577] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 145 eff = 0.997764
[17:02:21.577] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 199 eff = 0.997524
[17:02:21.577] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 164 eff = 0.998101
[17:02:21.578] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 164 eff = 0.998029
[17:02:21.578] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 157 eff = 0.997212
[17:02:21.578] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 152 eff = 0.997212
[17:02:21.578] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 167 eff = 0.997837
[17:02:21.579] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 142 eff = 0.996851
[17:02:21.579] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 155 eff = 0.998317
[17:02:21.579] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 153 eff = 0.999423
[17:02:21.579] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 152 eff = 0.998918
[17:02:25.876] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[17:02:25.876] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[17:02:25.876] INFO: ----------------------------------------------------------------------
[17:02:25.876] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:02:25.876] INFO: ----------------------------------------------------------------------
[17:02:25.876] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[17:02:25.876] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[17:02:25.876] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[17:02:25.876] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[17:02:25.876] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[17:02:25.876] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[17:02:25.876] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[17:02:25.876] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[17:02:25.876] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[17:02:25.876] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[17:02:25.877] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[17:02:25.877] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[17:02:25.895] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:02:26.562] INFO: Expecting 208000 events.
[17:02:41.750] INFO: 208000 events read in total (14661ms).
[17:02:41.767] INFO: Test took 15872ms.
[17:02:42.322] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:02:42.322] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3184282
[17:02:42.322] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[17:02:42.323] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:02:42.763] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 2 0 1
[17:02:42.763] INFO: number of red-efficiency pixels: 217 131 276 423 489 528 506 405 427 531 536 468 525 292 144 166
[17:02:42.763] INFO: number of X-ray hits detected: 133247 89759 155267 228514 245228 238727 249338 220724 214353 251523 249234 232083 237212 155635 93384 123498
[17:02:42.763] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:02:42.763] INFO: number of Vcal hits detected: 207779 207863 207700 207549 207466 207423 207436 207558 207535 207399 207352 207497 207374 207586 207849 207781
[17:02:42.763] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.8 99.8 99.8 99.7 99.7 99.8 99.7 99.9 99.9 99.9
[17:02:42.763] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.7 99.8 99.8 99.7 99.7 99.8 99.7 99.8 99.9 99.9
[17:02:42.763] INFO: X-ray hit rate [MHz/cm2]: 39.1 26.3 45.5 67.0 71.9 70.0 73.1 64.7 62.8 73.7 73.1 68.0 69.5 45.6 27.4 36.2
[17:02:42.763] INFO: PixTestHighRate::doXPixelAlive() done
[17:02:42.807] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[17:02:42.807] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[17:02:42.807] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[17:02:42.807] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[17:02:42.808] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[17:02:42.808] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[17:02:42.808] INFO: PixTest:: pg_setup set to default.
[17:02:48.076] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[17:02:48.076] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[17:02:50.676] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[17:02:50.676] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[17:02:50.676] INFO: ----------------------------------------------------------------------
[17:02:50.676] INFO: PixTestHighRate::runDaq() running for 2 seconds
[17:02:50.676] INFO: ----------------------------------------------------------------------
[17:02:50.877] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[17:02:50.877] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[17:02:50.877] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[17:02:50.877] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[17:02:50.877] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[17:02:50.877] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[17:02:50.877] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[17:02:50.877] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[17:02:50.877] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[17:02:50.877] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[17:02:50.877] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[17:02:50.877] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[17:02:51.834] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[17:02:53.837] INFO: data taking finished, elapsed time: 2 seconds.
[17:02:55.597] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39825 events with 594036 pixels
[17:02:55.604] INFO: PixTest:: pg_setup set to default.
[17:02:55.723] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[17:02:55.724] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[17:02:55.724] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[17:02:55.724] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[17:02:55.724] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[17:02:55.724] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[17:02:55.724] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[17:02:55.724] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[17:02:55.725] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[17:02:55.725] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[17:02:55.725] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[17:02:55.725] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[17:02:55.725] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[17:02:55.725] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[17:02:55.725] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[17:02:55.726] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[17:02:55.726] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[17:02:55.726] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[17:02:55.726] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[17:02:55.726] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[17:02:55.726] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[17:02:55.726] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[17:02:55.726] INFO: Pixels without X-ray hits (per ROC): 443 1016 585 130 112 135 106 138 248 99 120 218 115 553 1000 507
[17:02:55.726] INFO: PixTestHighRate::doRunDaq() done
[17:02:59.804] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[17:02:59.804] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C12_V0 -> hitMap_daqbbtest_mod
[17:03:07.004] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:03:07.005] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 381.9mA
[17:03:07.005] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[17:03:07.005] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[17:03:07.005] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:03:07.006] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:03:07.006] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:03:07.006] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:03:07.006] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:03:07.006] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:03:07.006] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:03:07.006] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:03:07.006] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:03:07.006] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[17:03:07.110] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:03:07.110] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:03:07.124] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:03:07.124] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:03:07.124] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:03:07.124] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[17:03:07.124] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:03:07.124] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:03:07.126] QUIET: Connection to board 58 closed.
[17:03:07.206] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[16:56:26.756] INFO: *** Welcome to pxar ***
[16:56:26.756] INFO: *** Today: 2016/09/29
[16:56:26.779] INFO: *** Version: v1.9.0-818-g96727
[16:56:26.779] INFO: readRocDacs: data/mq309/dacParameters35_C0.dat .. data/mq309/dacParameters35_C15.dat
[16:56:26.780] INFO: readTbmDacs: data/mq309/tbmParameters_C0a.dat .. data/mq309/tbmParameters_C0b.dat
[16:56:26.781] INFO: readMaskFile: data/mq309/defaultMaskFile.dat
[16:56:26.781] INFO: readTrimFile: data/mq309/trimParameters35_C0.dat .. data/mq309/trimParameters35_C15.dat
[16:56:26.931] INFO: clk: 4
[16:56:26.931] INFO: ctr: 4
[16:56:26.931] INFO: sda: 19
[16:56:26.931] INFO: tin: 9
[16:56:26.931] INFO: level: 15
[16:56:26.931] INFO: triggerdelay: 0
[16:56:26.931] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:56:26.931] INFO: Log level: DEBUG
[16:56:26.942] QUIET: Connection to board DTB_WRPRHI opened.
[16:56:26.945] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:56:26.948] INFO: RPC call hashes of host and DTB match: 398089610
[16:56:28.549] INFO: DUT info:
[16:56:28.549] INFO: The DUT currently contains the following objects:
[16:56:28.549] INFO: 2 TBM Cores tbm08c (2 ON)
[16:56:28.549] INFO: TBM Core alpha (0): 7 registers set
[16:56:28.549] INFO: TBM Core beta (1): 7 registers set
[16:56:28.549] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:56:28.549] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.549] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.549] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.549] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.549] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.549] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.549] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.549] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.550] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.550] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.550] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.550] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.550] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.550] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.550] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.550] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:56:28.550] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:56:28.551] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:56:28.552] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:56:28.553] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:56:28.553] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:56:28.553] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:56:28.553] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:56:28.553] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:56:28.553] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:56:28.555] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31588352
[16:56:28.555] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x13cb0e0
[16:56:28.555] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x116d370
[16:56:28.555] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f8e31d94010
[16:56:28.555] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f8e37fff510
[16:56:28.555] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31596544 fPxarMemory = 0x7f8e31d94010
[16:56:28.556] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[16:56:28.557] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[16:56:28.557] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[16:56:29.055] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x17b9fd0
[16:56:29.116] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:56:29.116] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:56:29.116] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:56:29.122] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:56:29.122] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:56:29.122] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:56:29.135] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:56:29.135] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:56:29.144] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:56:29.144] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:56:29.162] INFO: PixTestCmd::init()
[16:56:29.176] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:56:29.176] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:56:29.176] INFO: readGainPedestalParameters data/mq309/phCalibrationFitErr35_C0.dat .. data/mq309/phCalibrationFitErr35_C15.dat
[16:56:29.424] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:56:29.424] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:56:29.438] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:56:29.444] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:56:29.444] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:56:29.444] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:56:29.464] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:56:29.464] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:56:29.464] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:56:29.464] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:56:29.464] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:56:29.470] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:56:29.471] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:56:29.487] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:56:29.487] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:56:29.487] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:56:29.488] INFO: readReadbackCal: data/mq309/readbackCal_C0.dat .. data/mq309/readbackCal_C15.dat
[16:56:29.497] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:56:29.505] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:56:29.505] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:56:29.505] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:56:29.505] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:56:29.521] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:56:29.522] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:56:37.127] INFO: ######################################################################
[16:56:37.127] INFO: PixTestAlive::doTest()
[16:56:37.127] INFO: ######################################################################
[16:56:37.130] INFO: ----------------------------------------------------------------------
[16:56:37.130] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:56:37.130] INFO: ----------------------------------------------------------------------
[16:56:37.133] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:56:37.512] INFO: Expecting 41600 events.
[16:56:41.900] INFO: 41600 events read in total (3669ms).
[16:56:42.048] INFO: Test took 4915ms.
[16:56:42.058] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:56:42.058] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[16:56:42.058] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:56:42.331] INFO: PixTestAlive::aliveTest() done
[16:56:42.331] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 2 0 1
[16:56:42.331] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 43 35 49 63 81 90 101 83 76 83 81 71 79 59 34 40
[16:56:42.358] INFO: ----------------------------------------------------------------------
[16:56:42.358] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:56:42.358] INFO: ----------------------------------------------------------------------
[16:56:42.361] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:56:42.737] INFO: Expecting 41600 events.
[16:56:45.850] INFO: 41600 events read in total (2398ms).
[16:56:45.850] INFO: Test took 3489ms.
[16:56:45.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:56:45.850] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:56:45.850] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:56:45.851] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:56:46.273] INFO: PixTestAlive::maskTest() done
[16:56:46.273] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:56:46.295] INFO: ----------------------------------------------------------------------
[16:56:46.295] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:56:46.295] INFO: ----------------------------------------------------------------------
[16:56:46.297] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:56:46.674] INFO: Expecting 41600 events.
[16:56:51.053] INFO: 41600 events read in total (3663ms).
[16:56:51.054] INFO: Test took 4757ms.
[16:56:51.063] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:56:51.063] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[16:56:51.063] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:56:51.434] INFO: PixTestAlive::addressDecodingTest() done
[16:56:51.434] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:56:51.435] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[16:56:55.532] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:56:55.532] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[16:57:03.412] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:57:03.412] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:57:03.412] INFO: ----------------------------------------------------------------------
[16:57:03.412] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:57:03.412] INFO: ----------------------------------------------------------------------
[16:57:03.551] INFO: Expecting 768 events.
[16:57:04.684] INFO: 768 events read in total (418ms).
[16:57:04.685] INFO: Test took 1268ms.
[16:57:04.690] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:57:05.488] INFO: Expecting 41600 events.
[16:57:09.396] INFO: 41600 events read in total (3381ms).
[16:57:09.402] INFO: Test took 4712ms.
[16:57:09.560] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:09.560] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 907716
[16:57:09.560] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:57:09.560] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:57:09.627] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:57:10.294] INFO: Expecting 41600 events.
[16:57:14.292] INFO: 41600 events read in total (3472ms).
[16:57:14.297] INFO: Test took 4670ms.
[16:57:14.444] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:14.444] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 913032
[16:57:14.444] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:57:14.444] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:57:14.514] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:57:15.179] INFO: Expecting 41600 events.
[16:57:19.211] INFO: 41600 events read in total (3505ms).
[16:57:19.217] INFO: Test took 4703ms.
[16:57:19.365] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:19.366] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 913939
[16:57:19.366] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:57:19.366] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:57:19.436] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:57:20.101] INFO: Expecting 41600 events.
[16:57:24.098] INFO: 41600 events read in total (3471ms).
[16:57:24.104] INFO: Test took 4668ms.
[16:57:24.251] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:24.251] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 913784
[16:57:24.251] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:57:24.252] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:57:24.320] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:57:24.985] INFO: Expecting 41600 events.
[16:57:29.010] INFO: 41600 events read in total (3498ms).
[16:57:29.015] INFO: Test took 4694ms.
[16:57:29.163] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:29.163] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 915122
[16:57:29.163] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:57:29.163] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:57:29.233] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:57:29.898] INFO: Expecting 41600 events.
[16:57:33.967] INFO: 41600 events read in total (3543ms).
[16:57:33.973] INFO: Test took 4740ms.
[16:57:34.123] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:34.123] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 915087
[16:57:34.123] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:57:34.124] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:57:34.191] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:57:34.856] INFO: Expecting 41600 events.
[16:57:38.924] INFO: 41600 events read in total (3542ms).
[16:57:38.930] INFO: Test took 4739ms.
[16:57:39.078] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:39.078] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 915706
[16:57:39.078] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:57:39.078] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:57:39.147] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:57:39.813] INFO: Expecting 41600 events.
[16:57:43.885] INFO: 41600 events read in total (3546ms).
[16:57:43.891] INFO: Test took 4743ms.
[16:57:44.043] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:44.043] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 914581
[16:57:44.043] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:57:44.043] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:57:44.113] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:57:44.779] INFO: Expecting 41600 events.
[16:57:48.838] INFO: 41600 events read in total (3532ms).
[16:57:48.844] INFO: Test took 4731ms.
[16:57:48.993] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:48.993] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 913117
[16:57:48.993] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:57:48.993] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:57:49.063] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:57:49.729] INFO: Expecting 41600 events.
[16:57:53.803] INFO: 41600 events read in total (3547ms).
[16:57:53.809] INFO: Test took 4746ms.
[16:57:53.960] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:53.960] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 916699
[16:57:53.960] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:57:53.961] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:57:54.032] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:57:54.698] INFO: Expecting 41600 events.
[16:57:58.760] INFO: 41600 events read in total (3535ms).
[16:57:58.765] INFO: Test took 4733ms.
[16:57:58.920] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:58.920] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 916179
[16:57:58.920] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:57:58.920] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:57:58.988] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:57:59.656] INFO: Expecting 41600 events.
[16:58:03.714] INFO: 41600 events read in total (3532ms).
[16:58:03.720] INFO: Test took 4731ms.
[16:58:03.873] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:03.873] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 917374
[16:58:03.874] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:58:03.874] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:58:03.942] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:58:04.608] INFO: Expecting 41600 events.
[16:58:08.662] INFO: 41600 events read in total (3528ms).
[16:58:08.668] INFO: Test took 4726ms.
[16:58:08.819] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:08.819] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 915291
[16:58:08.819] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:58:08.820] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:58:08.889] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:58:09.555] INFO: Expecting 41600 events.
[16:58:13.620] INFO: 41600 events read in total (3539ms).
[16:58:13.626] INFO: Test took 4737ms.
[16:58:13.779] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:13.779] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 915892
[16:58:13.779] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:58:13.780] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:58:13.848] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:58:14.514] INFO: Expecting 41600 events.
[16:58:18.591] INFO: 41600 events read in total (3551ms).
[16:58:18.597] INFO: Test took 4749ms.
[16:58:18.745] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:18.745] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 916531
[16:58:18.745] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:58:18.745] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:58:18.815] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:58:19.480] INFO: Expecting 41600 events.
[16:58:23.550] INFO: 41600 events read in total (3544ms).
[16:58:23.556] INFO: Test took 4741ms.
[16:58:23.702] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:23.702] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 915134
[16:58:23.702] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:58:23.703] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:58:23.772] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:58:24.437] INFO: Expecting 41600 events.
[16:58:28.503] INFO: 41600 events read in total (3539ms).
[16:58:28.508] INFO: Test took 4736ms.
[16:58:28.656] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:28.656] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 917106
[16:58:28.656] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:58:28.657] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:58:28.727] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:58:29.393] INFO: Expecting 41600 events.
[16:58:33.453] INFO: 41600 events read in total (3533ms).
[16:58:33.459] INFO: Test took 4732ms.
[16:58:33.607] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:33.607] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 912773
[16:58:33.607] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:58:33.608] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:58:33.679] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:58:34.345] INFO: Expecting 41600 events.
[16:58:38.365] INFO: 41600 events read in total (3493ms).
[16:58:38.371] INFO: Test took 4692ms.
[16:58:38.519] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:38.519] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 912983
[16:58:38.519] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:58:38.519] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:58:38.589] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:58:39.254] INFO: Expecting 41600 events.
[16:58:43.121] INFO: 41600 events read in total (3340ms).
[16:58:43.127] INFO: Test took 4538ms.
[16:58:43.278] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:43.278] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 906098
[16:58:43.278] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:58:43.278] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:58:43.568] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 150 eff = 0.998389
[16:58:43.568] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 153 eff = 0.99899
[16:58:43.568] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 161 eff = 0.997212
[16:58:43.568] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 157 eff = 0.995889
[16:58:43.569] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 164 eff = 0.995072
[16:58:43.569] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 145 eff = 0.995697
[16:58:43.569] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 197 eff = 0.995288
[16:58:43.569] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 164 eff = 0.995529
[16:58:43.569] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 164 eff = 0.996082
[16:58:43.569] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 158 eff = 0.994591
[16:58:43.569] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 148 eff = 0.994255
[16:58:43.570] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 172 eff = 0.995505
[16:58:43.570] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 144 eff = 0.99512
[16:58:43.570] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 156 eff = 0.996683
[16:58:43.570] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 158 eff = 0.999063
[16:58:43.570] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 147 eff = 0.998606
[16:59:07.116] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:59:07.116] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:59:07.116] INFO: ----------------------------------------------------------------------
[16:59:07.116] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:59:07.116] INFO: ----------------------------------------------------------------------
[16:59:07.116] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:59:07.116] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:59:07.116] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:59:07.116] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:59:07.116] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:59:07.116] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:59:07.116] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:59:07.116] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:59:07.116] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:59:07.116] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:59:07.116] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:59:07.117] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:59:07.135] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:59:07.804] INFO: Expecting 208000 events.
[16:59:24.934] INFO: 208000 events read in total (16603ms).
[16:59:24.955] INFO: Test took 17820ms.
[16:59:25.719] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:25.719] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4265683
[16:59:25.719] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:59:25.719] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:59:26.238] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 2 0 1
[16:59:26.238] INFO: number of red-efficiency pixels: 313 239 472 746 923 968 894 747 733 976 976 794 902 490 236 243
[16:59:26.238] INFO: number of X-ray hits detected: 179426 121073 208755 307815 331087 320779 335741 295798 288806 338757 336742 312747 318908 210169 126544 165980
[16:59:26.238] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:59:26.238] INFO: number of Vcal hits detected: 207649 207750 207469 207154 206912 206855 206930 207118 207154 206825 206837 207062 206884 207333 207740 207697
[16:59:26.238] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.6 99.5 99.5 99.5 99.6 99.6 99.5 99.5 99.6 99.5 99.8 99.9 99.9
[16:59:26.238] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.5 99.4 99.5 99.6 99.6 99.4 99.4 99.5 99.5 99.7 99.9 99.9
[16:59:26.238] INFO: X-ray hit rate [MHz/cm2]: 52.6 35.5 61.2 90.2 97.0 94.0 98.4 86.7 84.7 99.3 98.7 91.7 93.5 61.6 37.1 48.6
[16:59:26.238] INFO: PixTestHighRate::doXPixelAlive() done
[16:59:26.285] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:59:26.285] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:59:26.285] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:59:26.285] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:59:26.285] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:59:26.285] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:59:26.285] INFO: PixTest:: pg_setup set to default.
[16:59:34.140] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:59:34.140] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[16:59:37.212] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[16:59:37.212] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[16:59:37.212] INFO: ----------------------------------------------------------------------
[16:59:37.212] INFO: PixTestHighRate::runDaq() running for 2 seconds
[16:59:37.212] INFO: ----------------------------------------------------------------------
[16:59:37.415] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[16:59:37.415] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[16:59:37.415] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[16:59:37.415] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[16:59:37.415] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[16:59:37.415] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[16:59:37.415] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[16:59:37.415] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[16:59:37.415] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[16:59:37.415] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[16:59:37.415] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[16:59:37.415] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[16:59:38.373] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[16:59:40.376] INFO: data taking finished, elapsed time: 2 seconds.
[16:59:42.344] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39819 events with 799746 pixels
[16:59:42.349] INFO: PixTest:: pg_setup set to default.
[16:59:42.451] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[16:59:42.451] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[16:59:42.451] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[16:59:42.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[16:59:42.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[16:59:42.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[16:59:42.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[16:59:42.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[16:59:42.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[16:59:42.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[16:59:42.453] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[16:59:42.453] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[16:59:42.453] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[16:59:42.453] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[16:59:42.453] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[16:59:42.453] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[16:59:42.454] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[16:59:42.454] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[16:59:42.454] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[16:59:42.454] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[16:59:42.454] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[16:59:42.454] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[16:59:42.454] INFO: Pixels without X-ray hits (per ROC): 285 765 481 90 81 82 72 104 167 74 63 156 67 449 845 338
[16:59:42.454] INFO: PixTestHighRate::doRunDaq() done
[16:59:46.660] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:59:46.660] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C12_V0 -> hitMap_daqbbtest_mod
[16:59:52.732] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:59:52.733] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 381.9mA
[16:59:52.733] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[16:59:52.733] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:59:52.733] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:59:52.733] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:59:52.733] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:59:52.734] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:59:52.734] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:59:52.734] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:59:52.734] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:59:52.734] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:59:52.734] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:59:52.734] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:59:52.843] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:59:52.844] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:59:52.857] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:59:52.857] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:59:52.857] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:59:52.857] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:59:52.857] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:59:52.857] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:59:52.860] QUIET: Connection to board 58 closed.
[16:59:52.939] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-904-gd94b9f2 on branch master