Test Date: 1973-11-29 16:33
Analysis date: 2016-08-29 18:42
Logfile
hrData_40.log
[11:59:17.628] INFO: *** Welcome to pxar ***
[11:59:17.628] INFO: *** Today: 2016/08/26
[11:59:17.654] INFO: *** Version: v1.9.0-818-g96727
[11:59:17.654] INFO: readRocDacs: data/mp238/dacParameters35_C0.dat .. data/mp238/dacParameters35_C15.dat
[11:59:17.655] INFO: readTbmDacs: data/mp238/tbmParameters_C0a.dat .. data/mp238/tbmParameters_C0b.dat
[11:59:17.655] INFO: readMaskFile: data/mp238/defaultMaskFile.dat
[11:59:17.655] INFO: readTrimFile: data/mp238/trimParameters35_C0.dat .. data/mp238/trimParameters35_C15.dat
[11:59:17.805] INFO: clk: 4
[11:59:17.805] INFO: ctr: 4
[11:59:17.805] INFO: sda: 19
[11:59:17.805] INFO: tin: 9
[11:59:17.805] INFO: level: 15
[11:59:17.805] INFO: triggerdelay: 0
[11:59:17.805] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:59:17.805] INFO: Log level: DEBUG
[11:59:17.816] QUIET: Connection to board DTB_WRPRHI opened.
[11:59:17.819] 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:
------------------------------------------------------
[11:59:17.822] INFO: RPC call hashes of host and DTB match: 398089610
[11:59:19.425] INFO: DUT info:
[11:59:19.425] INFO: The DUT currently contains the following objects:
[11:59:19.425] INFO: 2 TBM Cores tbm08c (2 ON)
[11:59:19.425] INFO: TBM Core alpha (0): 7 registers set
[11:59:19.425] INFO: TBM Core beta (1): 7 registers set
[11:59:19.425] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:59:19.425] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.425] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.425] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.425] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.425] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.425] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.426] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.426] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.426] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.426] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.426] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.426] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.426] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.426] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.426] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.426] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:59:19.426] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:59:19.427] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:59:19.428] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:59:19.429] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:59:19.431] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33161216
[11:59:19.432] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xd1f0e0
[11:59:19.432] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xac1370
[11:59:19.432] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f23f1d94010
[11:59:19.432] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f23f7fff510
[11:59:19.432] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33169408 fPxarMemory = 0x7f23f1d94010
[11:59:19.433] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 390.7mA
[11:59:19.434] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[11:59:19.434] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[11:59:19.933] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x110df70
[11:59:19.998] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:59:19.998] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:59:19.998] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:59:20.004] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:59:20.004] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:59:20.004] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:59:20.014] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:59:20.015] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:59:20.029] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:59:20.029] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:59:20.039] INFO: PixTestCmd::init()
[11:59:20.047] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:59:20.047] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:59:20.047] INFO: readGainPedestalParameters data/mp238/phCalibrationFitErr35_C0.dat .. data/mp238/phCalibrationFitErr35_C15.dat
[11:59:20.301] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:59:20.301] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:59:20.314] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:59:20.320] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:59:20.320] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:59:20.320] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:59:20.341] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:59:20.341] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:59:20.341] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:59:20.341] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:59:20.341] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:59:20.347] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:59:20.347] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:59:20.362] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:59:20.363] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:59:20.363] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:59:20.363] INFO: readReadbackCal: data/mp238/readbackCal_C0.dat .. data/mp238/readbackCal_C15.dat
[11:59:20.374] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:59:20.383] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:59:20.383] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:59:20.383] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:59:20.383] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:59:20.403] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:59:20.403] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:59:34.751] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[11:59:38.319] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[11:59:38.319] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[11:59:38.319] INFO: ----------------------------------------------------------------------
[11:59:38.319] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[11:59:38.319] INFO: ----------------------------------------------------------------------
[11:59:39.293] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[11:59:51.941] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[11:59:51.945] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:00:19.234] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1246188 events.
[12:00:25.176] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1246188, pixels seen in all events: 5297328
[12:00:25.284] INFO: Resuming triggers.
[12:00:37.936] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[12:00:38.035] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:01:05.328] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1246394 events.
[12:01:11.266] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1246394, pixels seen in all events: 5294807
[12:01:11.435] INFO: Resuming triggers.
[12:01:24.082] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[12:01:24.121] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:01:51.193] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1245990 events.
[12:01:57.156] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1245990, pixels seen in all events: 5299710
[12:01:57.326] INFO: Resuming triggers.
[12:02:09.980] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[12:02:10.020] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:02:37.063] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1246707 events.
[12:02:43.017] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1246707, pixels seen in all events: 5290498
[12:02:43.190] INFO: Resuming triggers.
[12:02:52.670] INFO: data taking finished, elapsed time: 60 seconds.
[12:02:52.866] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:03:13.085] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 933913 events.
[12:03:17.494] DEBUG: <PixTestXray.cc/processData:L823> # events read: 933913, pixels seen in all events: 3968513
[12:03:17.580] INFO: PixTest:: pg_setup set to default.
[12:03:17.669] INFO: PixTestXray::doPhRun() done
[12:03:57.391] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:03:57.392] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 393.9mA
[12:03:57.393] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[12:03:57.393] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[12:03:57.393] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:03:57.393] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:03:57.393] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:03:57.393] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:03:57.393] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:03:57.393] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:03:57.393] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:03:57.393] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:03:57.393] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:03:57.393] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:03:57.393] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:03:57.394] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:03:57.394] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:03:57.394] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:03:57.394] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:03:57.394] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:03:57.394] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:03:57.394] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:03:57.670] QUIET: Connection to board 58 closed.
[12:03:57.750] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[11:52:51.959] INFO: *** Welcome to pxar ***
[11:52:51.959] INFO: *** Today: 2016/08/26
[11:52:51.985] INFO: *** Version: v1.9.0-818-g96727
[11:52:51.985] INFO: readRocDacs: data/mp238/dacParameters35_C0.dat .. data/mp238/dacParameters35_C15.dat
[11:52:51.986] INFO: readTbmDacs: data/mp238/tbmParameters_C0a.dat .. data/mp238/tbmParameters_C0b.dat
[11:52:51.986] INFO: readMaskFile: data/mp238/defaultMaskFile.dat
[11:52:51.986] INFO: readTrimFile: data/mp238/trimParameters35_C0.dat .. data/mp238/trimParameters35_C15.dat
[11:52:52.136] INFO: clk: 4
[11:52:52.136] INFO: ctr: 4
[11:52:52.136] INFO: sda: 19
[11:52:52.136] INFO: tin: 9
[11:52:52.136] INFO: level: 15
[11:52:52.136] INFO: triggerdelay: 0
[11:52:52.136] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:52:52.136] INFO: Log level: DEBUG
[11:52:52.147] QUIET: Connection to board DTB_WRPRHI opened.
[11:52:52.150] 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:
------------------------------------------------------
[11:52:52.153] INFO: RPC call hashes of host and DTB match: 398089610
[11:52:53.756] INFO: DUT info:
[11:52:53.756] INFO: The DUT currently contains the following objects:
[11:52:53.756] INFO: 2 TBM Cores tbm08c (2 ON)
[11:52:53.756] INFO: TBM Core alpha (0): 7 registers set
[11:52:53.756] INFO: TBM Core beta (1): 7 registers set
[11:52:53.756] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:52:53.756] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.756] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.756] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.756] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.756] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.756] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.756] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.756] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.756] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.756] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.756] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.756] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.756] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.756] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.756] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.756] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:52:53.757] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:52:53.758] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:52:53.759] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:52:53.760] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:52:53.762] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31399936
[11:52:53.762] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x226d0e0
[11:52:53.762] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x200f370
[11:52:53.762] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fd1f3d83010
[11:52:53.762] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fd1f9fee510
[11:52:53.762] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31408128 fPxarMemory = 0x7fd1f3d83010
[11:52:53.763] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.9mA
[11:52:53.765] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[11:52:53.765] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[11:52:54.260] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x265bea0
[11:52:54.325] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:52:54.325] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:52:54.326] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:52:54.331] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:52:54.331] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:52:54.331] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:52:54.341] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:52:54.341] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:52:54.356] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:52:54.356] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:52:54.371] INFO: PixTestCmd::init()
[11:52:54.382] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:52:54.382] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:52:54.382] INFO: readGainPedestalParameters data/mp238/phCalibrationFitErr35_C0.dat .. data/mp238/phCalibrationFitErr35_C15.dat
[11:52:54.635] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:52:54.635] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:52:54.648] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:52:54.655] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:52:54.655] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:52:54.655] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:52:54.675] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:52:54.676] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:52:54.676] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:52:54.676] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:52:54.676] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:52:54.682] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:52:54.682] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:52:54.696] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:52:54.697] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:52:54.697] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:52:54.697] INFO: readReadbackCal: data/mp238/readbackCal_C0.dat .. data/mp238/readbackCal_C15.dat
[11:52:54.708] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:52:54.715] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:52:54.715] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:52:54.715] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:52:54.716] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:52:54.733] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:52:54.733] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:53:04.823] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[11:53:08.455] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[11:53:08.455] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[11:53:08.455] INFO: ----------------------------------------------------------------------
[11:53:08.455] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[11:53:08.455] INFO: ----------------------------------------------------------------------
[11:53:09.429] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[11:53:17.192] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[11:53:17.196] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:53:43.155] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 764827 events.
[11:53:55.155] DEBUG: <PixTestXray.cc/processData:L823> # events read: 764827, pixels seen in all events: 11075666
[11:53:55.409] INFO: Resuming triggers.
[11:54:03.178] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[11:54:03.216] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:54:28.929] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 765318 events.
[11:54:40.942] DEBUG: <PixTestXray.cc/processData:L823> # events read: 765318, pixels seen in all events: 11068205
[11:54:41.175] INFO: Resuming triggers.
[11:54:48.949] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:54:48.988] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:55:14.707] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 765847 events.
[11:55:26.746] DEBUG: <PixTestXray.cc/processData:L823> # events read: 765847, pixels seen in all events: 11062274
[11:55:26.949] INFO: Resuming triggers.
[11:55:34.724] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[11:55:34.793] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:56:00.453] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 765947 events.
[11:56:12.510] DEBUG: <PixTestXray.cc/processData:L823> # events read: 765947, pixels seen in all events: 11060562
[11:56:12.749] INFO: Resuming triggers.
[11:56:20.529] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[11:56:20.570] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:56:46.229] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 766438 events.
[11:56:58.295] DEBUG: <PixTestXray.cc/processData:L823> # events read: 766438, pixels seen in all events: 11054267
[11:56:58.499] INFO: Resuming triggers.
[11:57:06.275] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[11:57:06.338] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:57:32.072] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 766045 events.
[11:57:44.127] DEBUG: <PixTestXray.cc/processData:L823> # events read: 766045, pixels seen in all events: 11059376
[11:57:44.363] INFO: Resuming triggers.
[11:57:52.140] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[11:57:52.181] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:58:17.905] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 766168 events.
[11:58:29.941] DEBUG: <PixTestXray.cc/processData:L823> # events read: 766168, pixels seen in all events: 11058155
[11:58:30.177] INFO: Resuming triggers.
[11:58:35.840] INFO: data taking finished, elapsed time: 60 seconds.
[11:58:36.037] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:58:54.895] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 557944 events.
[11:59:03.672] DEBUG: <PixTestXray.cc/processData:L823> # events read: 557944, pixels seen in all events: 8042587
[11:59:03.861] INFO: PixTest:: pg_setup set to default.
[11:59:03.902] INFO: PixTestXray::doPhRun() done
[11:59:09.847] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:59:09.848] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 393.9mA
[11:59:09.848] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[11:59:09.848] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[11:59:09.848] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:59:09.849] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:59:09.849] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:59:09.849] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:59:09.849] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:59:09.849] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:59:09.849] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:59:09.849] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:59:09.849] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:59:09.849] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:59:09.849] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:59:09.849] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:59:09.849] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:59:09.849] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:59:09.850] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:59:09.850] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[11:59:09.850] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:59:09.850] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:59:10.158] QUIET: Connection to board 58 closed.
[11:59:10.238] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[11:45:27.639] INFO: *** Welcome to pxar ***
[11:45:27.639] INFO: *** Today: 2016/08/26
[11:45:27.662] INFO: *** Version: v1.9.0-818-g96727
[11:45:27.662] INFO: readRocDacs: data/mp238/dacParameters35_C0.dat .. data/mp238/dacParameters35_C15.dat
[11:45:27.663] INFO: readTbmDacs: data/mp238/tbmParameters_C0a.dat .. data/mp238/tbmParameters_C0b.dat
[11:45:27.663] INFO: readMaskFile: data/mp238/defaultMaskFile.dat
[11:45:27.663] INFO: readTrimFile: data/mp238/trimParameters35_C0.dat .. data/mp238/trimParameters35_C15.dat
[11:45:27.813] INFO: clk: 4
[11:45:27.813] INFO: ctr: 4
[11:45:27.813] INFO: sda: 19
[11:45:27.813] INFO: tin: 9
[11:45:27.813] INFO: level: 15
[11:45:27.813] INFO: triggerdelay: 0
[11:45:27.813] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:45:27.813] INFO: Log level: DEBUG
[11:45:27.824] QUIET: Connection to board DTB_WRPRHI opened.
[11:45:27.828] 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:
------------------------------------------------------
[11:45:27.830] INFO: RPC call hashes of host and DTB match: 398089610
[11:45:29.434] INFO: DUT info:
[11:45:29.434] INFO: The DUT currently contains the following objects:
[11:45:29.434] INFO: 2 TBM Cores tbm08c (2 ON)
[11:45:29.434] INFO: TBM Core alpha (0): 7 registers set
[11:45:29.434] INFO: TBM Core beta (1): 7 registers set
[11:45:29.434] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:45:29.434] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.434] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.434] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.434] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.434] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.435] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.435] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.435] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.435] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.435] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.435] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.435] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.435] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.435] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.435] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.435] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:45:29.435] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:45:29.436] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:45:29.437] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:45:29.438] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:45:29.440] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33021952
[11:45:29.440] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1a640e0
[11:45:29.440] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1806370
[11:45:29.440] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f6ab5d94010
[11:45:29.441] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f6abbfff510
[11:45:29.441] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33030144 fPxarMemory = 0x7f6ab5d94010
[11:45:29.442] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 390.7mA
[11:45:29.443] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[11:45:29.443] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[11:45:29.955] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1e52f60
[11:45:30.020] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:45:30.020] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:45:30.021] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:45:30.026] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:45:30.026] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:45:30.026] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:45:30.038] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:45:30.038] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:45:30.056] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:45:30.056] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:45:30.074] INFO: PixTestCmd::init()
[11:45:30.089] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:45:30.089] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:45:30.089] INFO: readGainPedestalParameters data/mp238/phCalibrationFitErr35_C0.dat .. data/mp238/phCalibrationFitErr35_C15.dat
[11:45:30.336] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:45:30.336] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:45:30.351] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:45:30.357] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:45:30.357] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:45:30.357] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:45:30.382] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:45:30.382] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:45:30.382] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:45:30.382] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:45:30.382] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:45:30.388] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:45:30.388] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:45:30.406] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:45:30.406] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:45:30.407] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:45:30.407] INFO: readReadbackCal: data/mp238/readbackCal_C0.dat .. data/mp238/readbackCal_C15.dat
[11:45:30.416] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:45:30.424] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:45:30.424] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:45:30.424] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:45:30.424] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:45:30.440] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:45:30.441] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:45:44.362] INFO: ######################################################################
[11:45:44.362] INFO: PixTestAlive::doTest()
[11:45:44.362] INFO: ######################################################################
[11:45:44.365] INFO: ----------------------------------------------------------------------
[11:45:44.365] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:45:44.365] INFO: ----------------------------------------------------------------------
[11:45:44.368] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:45:44.748] INFO: Expecting 41600 events.
[11:45:49.175] INFO: 41600 events read in total (3709ms).
[11:45:49.321] INFO: Test took 4953ms.
[11:45:49.331] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:45:49.331] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[11:45:49.331] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[11:45:49.608] INFO: PixTestAlive::aliveTest() done
[11:45:49.608] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:45:49.608] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 28 8 22 33 28 34 38 29 40 47 25 41 30 19 11 14
[11:45:49.635] INFO: ----------------------------------------------------------------------
[11:45:49.635] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:45:49.635] INFO: ----------------------------------------------------------------------
[11:45:49.637] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:45:50.016] INFO: Expecting 41600 events.
[11:45:53.128] INFO: 41600 events read in total (2396ms).
[11:45:53.128] INFO: Test took 3491ms.
[11:45:53.128] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:45:53.128] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[11:45:53.128] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[11:45:53.129] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:45:53.551] INFO: PixTestAlive::maskTest() done
[11:45:53.551] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:45:53.573] INFO: ----------------------------------------------------------------------
[11:45:53.573] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:45:53.573] INFO: ----------------------------------------------------------------------
[11:45:53.575] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:45:53.954] INFO: Expecting 41600 events.
[11:45:58.357] INFO: 41600 events read in total (3687ms).
[11:45:58.359] INFO: Test took 4784ms.
[11:45:58.370] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:45:58.370] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[11:45:58.370] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[11:45:58.740] INFO: PixTestAlive::addressDecodingTest() done
[11:45:58.740] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:45:58.741] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[11:46:10.359] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:46:10.359] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[11:46:14.807] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[11:46:14.807] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[11:46:14.807] INFO: ----------------------------------------------------------------------
[11:46:14.807] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:46:14.807] INFO: ----------------------------------------------------------------------
[11:46:14.946] INFO: Expecting 768 events.
[11:46:16.080] INFO: 768 events read in total (419ms).
[11:46:16.081] INFO: Test took 1269ms.
[11:46:16.087] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:46:16.884] INFO: Expecting 41600 events.
[11:46:20.255] INFO: 41600 events read in total (2844ms).
[11:46:20.259] INFO: Test took 4172ms.
[11:46:20.337] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:20.337] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 442226
[11:46:20.337] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[11:46:20.337] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:46:20.373] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:46:21.040] INFO: Expecting 41600 events.
[11:46:24.549] INFO: 41600 events read in total (2982ms).
[11:46:24.552] INFO: Test took 4179ms.
[11:46:24.626] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:24.626] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 449637
[11:46:24.626] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[11:46:24.627] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:46:24.664] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:46:25.330] INFO: Expecting 41600 events.
[11:46:28.850] INFO: 41600 events read in total (2994ms).
[11:46:28.854] INFO: Test took 4190ms.
[11:46:28.929] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:28.929] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 451256
[11:46:28.929] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[11:46:28.929] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:46:28.967] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:46:29.632] INFO: Expecting 41600 events.
[11:46:33.156] INFO: 41600 events read in total (2997ms).
[11:46:33.159] INFO: Test took 4192ms.
[11:46:33.234] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:33.234] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452428
[11:46:33.234] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[11:46:33.234] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:46:33.272] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:46:33.937] INFO: Expecting 41600 events.
[11:46:37.450] INFO: 41600 events read in total (2986ms).
[11:46:37.454] INFO: Test took 4182ms.
[11:46:37.529] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:37.529] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 451740
[11:46:37.529] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[11:46:37.530] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:46:37.569] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:46:38.234] INFO: Expecting 41600 events.
[11:46:41.774] INFO: 41600 events read in total (3014ms).
[11:46:41.777] INFO: Test took 4208ms.
[11:46:41.852] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:41.852] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452535
[11:46:41.852] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[11:46:41.853] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:46:41.891] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:46:42.556] INFO: Expecting 41600 events.
[11:46:46.072] INFO: 41600 events read in total (2989ms).
[11:46:46.075] INFO: Test took 4184ms.
[11:46:46.149] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:46.149] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452539
[11:46:46.149] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[11:46:46.150] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:46:46.188] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:46:46.856] INFO: Expecting 41600 events.
[11:46:50.463] INFO: 41600 events read in total (3081ms).
[11:46:50.466] INFO: Test took 4278ms.
[11:46:50.545] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:50.545] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 453123
[11:46:50.545] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[11:46:50.545] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:46:50.585] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:46:51.266] INFO: Expecting 41600 events.
[11:46:54.871] INFO: 41600 events read in total (3078ms).
[11:46:54.875] INFO: Test took 4290ms.
[11:46:54.955] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:54.955] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452909
[11:46:54.956] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[11:46:54.956] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:46:55.003] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:46:55.672] INFO: Expecting 41600 events.
[11:46:59.248] INFO: 41600 events read in total (3049ms).
[11:46:59.251] INFO: Test took 4248ms.
[11:46:59.328] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:46:59.328] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452634
[11:46:59.328] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[11:46:59.328] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:46:59.367] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:47:00.033] INFO: Expecting 41600 events.
[11:47:03.609] INFO: 41600 events read in total (3049ms).
[11:47:03.613] INFO: Test took 4246ms.
[11:47:03.689] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:03.689] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452099
[11:47:03.689] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[11:47:03.690] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:47:03.728] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:47:04.395] INFO: Expecting 41600 events.
[11:47:07.977] INFO: 41600 events read in total (3055ms).
[11:47:07.981] INFO: Test took 4253ms.
[11:47:08.057] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:08.057] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452571
[11:47:08.057] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[11:47:08.058] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:47:08.096] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:47:08.764] INFO: Expecting 41600 events.
[11:47:12.338] INFO: 41600 events read in total (3048ms).
[11:47:12.342] INFO: Test took 4245ms.
[11:47:12.417] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:12.417] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452315
[11:47:12.417] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[11:47:12.418] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:47:12.456] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:47:13.121] INFO: Expecting 41600 events.
[11:47:16.673] INFO: 41600 events read in total (3025ms).
[11:47:16.676] INFO: Test took 4221ms.
[11:47:16.751] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:16.751] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452772
[11:47:16.751] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[11:47:16.751] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:47:16.790] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:47:17.456] INFO: Expecting 41600 events.
[11:47:20.998] INFO: 41600 events read in total (3015ms).
[11:47:21.002] INFO: Test took 4212ms.
[11:47:21.079] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:21.079] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452636
[11:47:21.079] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[11:47:21.079] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:47:21.118] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:47:21.786] INFO: Expecting 41600 events.
[11:47:25.358] INFO: 41600 events read in total (3039ms).
[11:47:25.361] INFO: Test took 4242ms.
[11:47:25.436] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:25.436] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452127
[11:47:25.436] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[11:47:25.437] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:47:25.476] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:47:26.141] INFO: Expecting 41600 events.
[11:47:29.718] INFO: 41600 events read in total (3050ms).
[11:47:29.722] INFO: Test took 4246ms.
[11:47:29.796] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:29.796] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 453416
[11:47:29.796] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[11:47:29.797] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:47:29.835] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:47:30.501] INFO: Expecting 41600 events.
[11:47:34.063] INFO: 41600 events read in total (3035ms).
[11:47:34.067] INFO: Test took 4231ms.
[11:47:34.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:34.141] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 451112
[11:47:34.142] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[11:47:34.142] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:47:34.180] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:47:34.846] INFO: Expecting 41600 events.
[11:47:38.368] INFO: 41600 events read in total (2995ms).
[11:47:38.372] INFO: Test took 4192ms.
[11:47:38.448] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:38.449] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 451326
[11:47:38.449] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[11:47:38.449] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:47:38.488] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:47:39.157] INFO: Expecting 41600 events.
[11:47:42.570] INFO: 41600 events read in total (2886ms).
[11:47:42.573] INFO: Test took 4085ms.
[11:47:42.651] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:42.651] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 446247
[11:47:42.651] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[11:47:42.652] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:47:42.915] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 155 eff = 0.999688
[11:47:42.916] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 161 eff = 0.999856
[11:47:42.916] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 164 eff = 0.999615
[11:47:42.916] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 157 eff = 0.999207
[11:47:42.916] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 156 eff = 0.999111
[11:47:42.917] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 161 eff = 0.999038
[11:47:42.917] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 169 eff = 0.999159
[11:47:42.917] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 150 eff = 0.999351
[11:47:42.917] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 152 eff = 0.99899
[11:47:42.918] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 171 eff = 0.99899
[11:47:42.918] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 157 eff = 0.998966
[11:47:42.918] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 152 eff = 0.999135
[11:47:42.918] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 150 eff = 0.999087
[11:47:42.919] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 145 eff = 0.999519
[11:47:42.919] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 133 eff = 0.999784
[11:47:42.919] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 151 eff = 0.999808
[11:47:46.503] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[11:47:46.503] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[11:47:46.503] INFO: ----------------------------------------------------------------------
[11:47:46.503] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:47:46.503] INFO: ----------------------------------------------------------------------
[11:47:46.503] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[11:47:46.503] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[11:47:46.503] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[11:47:46.503] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[11:47:46.503] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[11:47:46.503] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[11:47:46.503] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[11:47:46.504] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[11:47:46.504] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[11:47:46.504] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[11:47:46.504] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[11:47:46.504] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[11:47:46.523] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:47:47.194] INFO: Expecting 208000 events.
[11:48:00.710] INFO: 208000 events read in total (12989ms).
[11:48:00.722] INFO: Test took 14199ms.
[11:48:01.120] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:48:01.120] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1980876
[11:48:01.120] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[11:48:01.121] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:48:01.489] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:48:01.489] INFO: number of red-efficiency pixels: 105 56 133 204 224 241 205 203 254 227 228 247 193 142 65 87
[11:48:01.489] INFO: number of X-ray hits detected: 80967 56965 93819 140494 149026 149212 147379 138274 149331 154127 152360 145176 141745 94594 56069 64778
[11:48:01.489] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:48:01.489] INFO: number of Vcal hits detected: 207895 207940 207862 207793 207773 207747 207783 207793 207738 207762 207762 207743 207800 207852 207935 207912
[11:48:01.489] 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 100.0
[11:48:01.489] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[11:48:01.489] INFO: X-ray hit rate [MHz/cm2]: 23.7 16.7 27.5 41.2 43.7 43.7 43.2 40.5 43.8 45.2 44.7 42.6 41.5 27.7 16.4 19.0
[11:48:01.489] INFO: PixTestHighRate::doXPixelAlive() done
[11:48:01.536] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[11:48:01.536] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[11:48:01.536] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[11:48:01.536] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[11:48:01.536] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[11:48:01.536] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[11:48:01.536] INFO: PixTest:: pg_setup set to default.
[11:48:06.727] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:48:06.727] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[11:48:11.983] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[11:48:11.983] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[11:48:11.983] INFO: ----------------------------------------------------------------------
[11:48:11.983] INFO: PixTestHighRate::runDaq() running for 2 seconds
[11:48:11.983] INFO: ----------------------------------------------------------------------
[11:48:12.195] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[11:48:12.195] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[11:48:12.195] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[11:48:12.195] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[11:48:12.195] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[11:48:12.195] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[11:48:12.195] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[11:48:12.195] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[11:48:12.195] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[11:48:12.195] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[11:48:12.195] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[11:48:12.195] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[11:48:13.152] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[11:48:15.155] INFO: data taking finished, elapsed time: 2 seconds.
[11:48:16.669] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39822 events with 363778 pixels
[11:48:16.674] INFO: PixTest:: pg_setup set to default.
[11:48:16.815] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[11:48:16.815] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[11:48:16.816] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[11:48:16.816] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[11:48:16.816] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[11:48:16.816] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[11:48:16.816] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[11:48:16.816] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[11:48:16.817] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[11:48:16.817] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[11:48:16.817] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[11:48:16.817] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[11:48:16.817] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[11:48:16.817] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[11:48:16.817] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[11:48:16.818] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[11:48:16.818] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[11:48:16.818] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[11:48:16.818] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[11:48:16.818] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[11:48:16.818] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[11:48:16.818] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[11:48:16.818] INFO: Pixels without X-ray hits (per ROC): 781 1367 786 224 241 247 251 189 182 236 241 329 250 806 1415 986
[11:48:16.818] INFO: PixTestHighRate::doRunDaq() done
[11:48:22.679] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:48:22.679] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C11_V0 -> hitMap_daqbbtest_mod
[11:48:37.527] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:48:37.528] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 393.9mA
[11:48:37.528] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[11:48:37.528] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[11:48:37.528] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:48:37.529] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:48:37.529] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:48:37.529] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:48:37.529] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:48:37.529] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:48:37.529] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:48:37.529] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:48:37.529] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:48:37.529] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:48:37.632] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:48:37.632] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:48:37.646] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:48:37.646] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:48:37.646] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:48:37.646] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[11:48:37.646] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:48:37.646] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:48:37.649] QUIET: Connection to board 58 closed.
[11:48:37.728] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[11:41:10.930] INFO: *** Welcome to pxar ***
[11:41:10.930] INFO: *** Today: 2016/08/26
[11:41:10.955] INFO: *** Version: v1.9.0-818-g96727
[11:41:10.955] INFO: readRocDacs: data/mp238/dacParameters35_C0.dat .. data/mp238/dacParameters35_C15.dat
[11:41:10.956] INFO: readTbmDacs: data/mp238/tbmParameters_C0a.dat .. data/mp238/tbmParameters_C0b.dat
[11:41:10.957] INFO: readMaskFile: data/mp238/defaultMaskFile.dat
[11:41:10.957] INFO: readTrimFile: data/mp238/trimParameters35_C0.dat .. data/mp238/trimParameters35_C15.dat
[11:41:11.108] INFO: clk: 4
[11:41:11.109] INFO: ctr: 4
[11:41:11.109] INFO: sda: 19
[11:41:11.109] INFO: tin: 9
[11:41:11.109] INFO: level: 15
[11:41:11.109] INFO: triggerdelay: 0
[11:41:11.109] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:41:11.109] INFO: Log level: DEBUG
[11:41:11.119] QUIET: Connection to board DTB_WRPRHI opened.
[11:41:11.123] 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:
------------------------------------------------------
[11:41:11.125] INFO: RPC call hashes of host and DTB match: 398089610
[11:41:12.727] INFO: DUT info:
[11:41:12.727] INFO: The DUT currently contains the following objects:
[11:41:12.727] INFO: 2 TBM Cores tbm08c (2 ON)
[11:41:12.727] INFO: TBM Core alpha (0): 7 registers set
[11:41:12.727] INFO: TBM Core beta (1): 7 registers set
[11:41:12.727] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:41:12.727] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.727] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.727] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.728] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.728] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.728] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.728] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.728] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.728] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.728] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.728] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.728] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.728] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.728] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.728] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.728] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:12.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:41:12.728] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:41:12.728] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:41:12.728] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:41:12.728] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:41:12.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:41:12.728] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:41:12.728] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:41:12.728] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:41:12.728] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:41:12.728] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:41:12.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:41:12.728] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:41:12.729] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:41:12.730] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:41:12.731] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:41:12.734] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31354880
[11:41:12.734] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x25a10e0
[11:41:12.734] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2343370
[11:41:12.734] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f96f9d94010
[11:41:12.734] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f96fffff510
[11:41:12.734] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31363072 fPxarMemory = 0x7f96f9d94010
[11:41:12.735] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 390.7mA
[11:41:12.736] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[11:41:12.736] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[11:41:13.235] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x298ff70
[11:41:13.297] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:41:13.298] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:41:13.298] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:41:13.303] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:41:13.304] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:41:13.304] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:41:13.315] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:41:13.315] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:41:13.336] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:41:13.336] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:41:13.353] INFO: PixTestCmd::init()
[11:41:13.359] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:41:13.359] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:41:13.359] INFO: readGainPedestalParameters data/mp238/phCalibrationFitErr35_C0.dat .. data/mp238/phCalibrationFitErr35_C15.dat
[11:41:13.607] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:41:13.607] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:41:13.622] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:41:13.629] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:41:13.629] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:41:13.629] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:41:13.648] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:41:13.648] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:41:13.648] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:41:13.648] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:41:13.648] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:41:13.654] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:41:13.654] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:41:13.669] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:41:13.669] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:41:13.669] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:41:13.669] INFO: readReadbackCal: data/mp238/readbackCal_C0.dat .. data/mp238/readbackCal_C15.dat
[11:41:13.678] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:41:13.686] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:41:13.686] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:41:13.686] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:41:13.686] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:41:13.701] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:41:13.701] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:41:22.842] INFO: ######################################################################
[11:41:22.842] INFO: PixTestAlive::doTest()
[11:41:22.842] INFO: ######################################################################
[11:41:22.845] INFO: ----------------------------------------------------------------------
[11:41:22.845] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:41:22.845] INFO: ----------------------------------------------------------------------
[11:41:22.848] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:41:23.227] INFO: Expecting 41600 events.
[11:41:27.640] INFO: 41600 events read in total (3695ms).
[11:41:27.785] INFO: Test took 4937ms.
[11:41:27.797] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:41:27.797] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[11:41:27.797] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[11:41:28.072] INFO: PixTestAlive::aliveTest() done
[11:41:28.072] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:41:28.072] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 33 21 35 58 60 58 43 62 59 55 56 48 62 39 23 20
[11:41:28.098] INFO: ----------------------------------------------------------------------
[11:41:28.098] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:41:28.099] INFO: ----------------------------------------------------------------------
[11:41:28.101] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:41:28.479] INFO: Expecting 41600 events.
[11:41:31.578] INFO: 41600 events read in total (2384ms).
[11:41:31.579] INFO: Test took 3478ms.
[11:41:31.579] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:41:31.579] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[11:41:31.579] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[11:41:31.579] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:41:32.001] INFO: PixTestAlive::maskTest() done
[11:41:32.001] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:41:32.024] INFO: ----------------------------------------------------------------------
[11:41:32.024] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:41:32.024] INFO: ----------------------------------------------------------------------
[11:41:32.026] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:41:32.404] INFO: Expecting 41600 events.
[11:41:36.767] INFO: 41600 events read in total (3648ms).
[11:41:36.769] INFO: Test took 4743ms.
[11:41:36.778] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:41:36.778] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[11:41:36.778] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[11:41:37.148] INFO: PixTestAlive::addressDecodingTest() done
[11:41:37.148] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:41:37.148] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[11:41:44.471] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:41:44.471] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[11:41:47.599] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[11:41:47.599] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[11:41:47.599] INFO: ----------------------------------------------------------------------
[11:41:47.599] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:41:47.599] INFO: ----------------------------------------------------------------------
[11:41:47.738] INFO: Expecting 768 events.
[11:41:48.871] INFO: 768 events read in total (418ms).
[11:41:48.871] INFO: Test took 1267ms.
[11:41:48.877] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:41:49.675] INFO: Expecting 41600 events.
[11:41:53.277] INFO: 41600 events read in total (3075ms).
[11:41:53.282] INFO: Test took 4405ms.
[11:41:53.400] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:41:53.400] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 674408
[11:41:53.400] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[11:41:53.401] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:41:53.451] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:41:54.116] INFO: Expecting 41600 events.
[11:41:57.821] INFO: 41600 events read in total (3178ms).
[11:41:57.825] INFO: Test took 4374ms.
[11:41:57.938] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:41:57.938] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 681221
[11:41:57.938] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[11:41:57.939] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:41:57.991] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:41:58.656] INFO: Expecting 41600 events.
[11:42:02.346] INFO: 41600 events read in total (3163ms).
[11:42:02.350] INFO: Test took 4359ms.
[11:42:02.464] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:02.465] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683728
[11:42:02.465] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[11:42:02.465] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:42:02.517] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:42:03.182] INFO: Expecting 41600 events.
[11:42:06.905] INFO: 41600 events read in total (3196ms).
[11:42:06.910] INFO: Test took 4393ms.
[11:42:07.026] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:07.026] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683418
[11:42:07.026] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[11:42:07.026] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:42:07.080] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:42:07.745] INFO: Expecting 41600 events.
[11:42:11.469] INFO: 41600 events read in total (3197ms).
[11:42:11.474] INFO: Test took 4394ms.
[11:42:11.591] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:11.591] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684599
[11:42:11.591] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[11:42:11.591] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:42:11.644] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:42:12.309] INFO: Expecting 41600 events.
[11:42:16.043] INFO: 41600 events read in total (3207ms).
[11:42:16.048] INFO: Test took 4404ms.
[11:42:16.163] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:16.163] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683800
[11:42:16.163] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[11:42:16.163] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:42:16.216] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:42:16.881] INFO: Expecting 41600 events.
[11:42:20.624] INFO: 41600 events read in total (3216ms).
[11:42:20.629] INFO: Test took 4413ms.
[11:42:20.745] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:20.745] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685055
[11:42:20.746] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[11:42:20.746] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:42:20.801] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:42:21.467] INFO: Expecting 41600 events.
[11:42:25.225] INFO: 41600 events read in total (3231ms).
[11:42:25.230] INFO: Test took 4429ms.
[11:42:25.347] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:25.347] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684564
[11:42:25.347] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[11:42:25.348] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:42:25.401] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:42:26.066] INFO: Expecting 41600 events.
[11:42:29.785] INFO: 41600 events read in total (3193ms).
[11:42:29.789] INFO: Test took 4388ms.
[11:42:29.903] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:29.903] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683204
[11:42:29.903] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[11:42:29.903] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:42:29.956] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:42:30.621] INFO: Expecting 41600 events.
[11:42:34.374] INFO: 41600 events read in total (3227ms).
[11:42:34.378] INFO: Test took 4422ms.
[11:42:34.496] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:34.496] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685439
[11:42:34.496] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[11:42:34.496] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:42:34.551] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:42:35.217] INFO: Expecting 41600 events.
[11:42:38.950] INFO: 41600 events read in total (3207ms).
[11:42:38.955] INFO: Test took 4404ms.
[11:42:39.071] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:39.071] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684387
[11:42:39.071] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[11:42:39.072] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:42:39.126] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:42:39.791] INFO: Expecting 41600 events.
[11:42:43.536] INFO: 41600 events read in total (3218ms).
[11:42:43.541] INFO: Test took 4415ms.
[11:42:43.656] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:43.656] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684770
[11:42:43.656] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[11:42:43.657] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:42:43.709] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:42:44.375] INFO: Expecting 41600 events.
[11:42:48.118] INFO: 41600 events read in total (3217ms).
[11:42:48.122] INFO: Test took 4413ms.
[11:42:48.239] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:48.239] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684478
[11:42:48.239] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[11:42:48.239] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:42:48.293] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:42:48.957] INFO: Expecting 41600 events.
[11:42:52.719] INFO: 41600 events read in total (3235ms).
[11:42:52.723] INFO: Test took 4430ms.
[11:42:52.838] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:52.838] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685388
[11:42:52.838] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[11:42:52.838] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:42:52.892] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:42:53.557] INFO: Expecting 41600 events.
[11:42:57.284] INFO: 41600 events read in total (3200ms).
[11:42:57.288] INFO: Test took 4396ms.
[11:42:57.405] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:42:57.405] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685036
[11:42:57.405] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[11:42:57.405] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:42:57.460] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:42:58.126] INFO: Expecting 41600 events.
[11:43:01.890] INFO: 41600 events read in total (3238ms).
[11:43:01.894] INFO: Test took 4434ms.
[11:43:02.010] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:43:02.010] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683956
[11:43:02.010] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[11:43:02.011] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:43:02.064] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:43:02.730] INFO: Expecting 41600 events.
[11:43:06.517] INFO: 41600 events read in total (3260ms).
[11:43:06.523] INFO: Test took 4459ms.
[11:43:06.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:43:06.645] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684715
[11:43:06.645] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[11:43:06.646] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:43:06.700] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:43:07.378] INFO: Expecting 41600 events.
[11:43:11.142] INFO: 41600 events read in total (3237ms).
[11:43:11.147] INFO: Test took 4447ms.
[11:43:11.265] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:43:11.265] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 682864
[11:43:11.265] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[11:43:11.266] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:43:11.319] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:43:11.987] INFO: Expecting 41600 events.
[11:43:15.767] INFO: 41600 events read in total (3253ms).
[11:43:15.772] INFO: Test took 4453ms.
[11:43:15.891] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:43:15.891] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 682572
[11:43:15.892] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[11:43:15.892] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:43:15.945] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:43:16.611] INFO: Expecting 41600 events.
[11:43:20.207] INFO: 41600 events read in total (3069ms).
[11:43:20.213] INFO: Test took 4268ms.
[11:43:20.329] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:43:20.329] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 677031
[11:43:20.329] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[11:43:20.330] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:43:20.605] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 164 eff = 0.999183
[11:43:20.605] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 161 eff = 0.999663
[11:43:20.605] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 158 eff = 0.998702
[11:43:20.605] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 157 eff = 0.997644
[11:43:20.606] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 160 eff = 0.997885
[11:43:20.606] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 168 eff = 0.99774
[11:43:20.606] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 163 eff = 0.99762
[11:43:20.606] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 158 eff = 0.997933
[11:43:20.606] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 152 eff = 0.997788
[11:43:20.606] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 172 eff = 0.997692
[11:43:20.607] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 157 eff = 0.997861
[11:43:20.607] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 156 eff = 0.997981
[11:43:20.607] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 149 eff = 0.997957
[11:43:20.607] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 152 eff = 0.998822
[11:43:20.607] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 136 eff = 0.999639
[11:43:20.608] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 167 eff = 0.999399
[11:43:56.711] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[11:43:56.711] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[11:43:56.711] INFO: ----------------------------------------------------------------------
[11:43:56.711] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:43:56.711] INFO: ----------------------------------------------------------------------
[11:43:56.711] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[11:43:56.711] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[11:43:56.711] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[11:43:56.711] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[11:43:56.711] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[11:43:56.711] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[11:43:56.711] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[11:43:56.711] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[11:43:56.711] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[11:43:56.711] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[11:43:56.711] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[11:43:56.711] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[11:43:56.729] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:43:57.397] INFO: Expecting 208000 events.
[11:44:12.503] INFO: 208000 events read in total (14579ms).
[11:44:12.519] INFO: Test took 15790ms.
[11:44:13.115] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:44:13.115] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3118866
[11:44:13.115] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[11:44:13.116] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:44:13.560] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:44:13.560] INFO: number of red-efficiency pixels: 167 105 285 450 483 415 479 424 431 490 456 463 492 293 124 120
[11:44:13.560] INFO: number of X-ray hits detected: 129478 91053 149226 224620 237709 237503 235558 221111 237488 245163 242290 231256 226511 150862 88401 104077
[11:44:13.560] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:44:13.560] INFO: number of Vcal hits detected: 207830 207892 207690 207524 207469 207546 207476 207544 207535 207463 207495 207494 207460 207684 207874 207879
[11:44:13.560] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[11:44:13.560] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.7 99.8 99.8 99.7 99.8 99.8 99.7 99.8 99.9 99.9
[11:44:13.560] INFO: X-ray hit rate [MHz/cm2]: 38.0 26.7 43.7 65.8 69.7 69.6 69.0 64.8 69.6 71.9 71.0 67.8 66.4 44.2 25.9 30.5
[11:44:13.560] INFO: PixTestHighRate::doXPixelAlive() done
[11:44:13.611] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[11:44:13.611] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[11:44:13.611] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[11:44:13.611] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[11:44:13.611] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[11:44:13.611] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[11:44:13.611] INFO: PixTest:: pg_setup set to default.
[11:45:08.807] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:45:08.807] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[11:45:10.255] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[11:45:10.255] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[11:45:10.255] INFO: ----------------------------------------------------------------------
[11:45:10.255] INFO: PixTestHighRate::runDaq() running for 2 seconds
[11:45:10.255] INFO: ----------------------------------------------------------------------
[11:45:10.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[11:45:10.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[11:45:10.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[11:45:10.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[11:45:10.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[11:45:10.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[11:45:10.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[11:45:10.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[11:45:10.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[11:45:10.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[11:45:10.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[11:45:10.452] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[11:45:11.410] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[11:45:13.413] INFO: data taking finished, elapsed time: 2 seconds.
[11:45:15.142] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39825 events with 583571 pixels
[11:45:15.148] INFO: PixTest:: pg_setup set to default.
[11:45:15.267] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[11:45:15.267] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[11:45:15.267] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[11:45:15.268] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[11:45:15.268] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[11:45:15.268] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[11:45:15.268] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[11:45:15.268] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[11:45:15.268] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[11:45:15.268] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[11:45:15.269] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[11:45:15.269] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[11:45:15.269] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[11:45:15.269] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[11:45:15.269] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[11:45:15.269] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[11:45:15.270] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[11:45:15.270] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[11:45:15.270] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[11:45:15.270] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[11:45:15.270] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[11:45:15.270] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[11:45:15.270] INFO: Pixels without X-ray hits (per ROC): 425 999 616 136 118 107 117 115 107 108 116 222 126 582 994 620
[11:45:15.270] INFO: PixTestHighRate::doRunDaq() done
[11:45:17.871] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:45:17.871] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C12_V0 -> hitMap_daqbbtest_mod
[11:45:20.191] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:45:20.191] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 393.9mA
[11:45:20.192] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 469.5mA
[11:45:20.192] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[11:45:20.192] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:45:20.192] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:45:20.192] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:45:20.192] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:45:20.193] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:45:20.193] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:45:20.193] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:45:20.193] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:45:20.193] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:45:20.193] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:45:20.301] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:45:20.301] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:45:20.314] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:45:20.315] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:45:20.315] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:45:20.315] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[11:45:20.315] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:45:20.315] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:45:20.317] QUIET: Connection to board 58 closed.
[11:45:20.397] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[11:37:44.412] INFO: *** Welcome to pxar ***
[11:37:44.412] INFO: *** Today: 2016/08/26
[11:37:44.436] INFO: *** Version: v1.9.0-818-g96727
[11:37:44.436] INFO: readRocDacs: data/mp238/dacParameters35_C0.dat .. data/mp238/dacParameters35_C15.dat
[11:37:44.437] INFO: readTbmDacs: data/mp238/tbmParameters_C0a.dat .. data/mp238/tbmParameters_C0b.dat
[11:37:44.438] INFO: readMaskFile: data/mp238/defaultMaskFile.dat
[11:37:44.438] INFO: readTrimFile: data/mp238/trimParameters35_C0.dat .. data/mp238/trimParameters35_C15.dat
[11:37:44.587] INFO: clk: 4
[11:37:44.587] INFO: ctr: 4
[11:37:44.587] INFO: sda: 19
[11:37:44.587] INFO: tin: 9
[11:37:44.587] INFO: level: 15
[11:37:44.587] INFO: triggerdelay: 0
[11:37:44.588] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:37:44.588] INFO: Log level: DEBUG
[11:37:44.599] QUIET: Connection to board DTB_WRPRHI opened.
[11:37:44.602] 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:
------------------------------------------------------
[11:37:44.605] INFO: RPC call hashes of host and DTB match: 398089610
[11:37:46.207] INFO: DUT info:
[11:37:46.207] INFO: The DUT currently contains the following objects:
[11:37:46.207] INFO: 2 TBM Cores tbm08c (2 ON)
[11:37:46.207] INFO: TBM Core alpha (0): 7 registers set
[11:37:46.207] INFO: TBM Core beta (1): 7 registers set
[11:37:46.207] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:37:46.207] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:37:46.208] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:37:46.208] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:37:46.208] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:37:46.208] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:37:46.208] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:37:46.208] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:37:46.208] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:37:46.208] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:37:46.209] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:37:46.210] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:37:46.211] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:37:46.214] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31469568
[11:37:46.214] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xd0e0e0
[11:37:46.214] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xab0370
[11:37:46.214] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fa625d94010
[11:37:46.214] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fa62bfff510
[11:37:46.214] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31477760 fPxarMemory = 0x7fa625d94010
[11:37:46.215] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.9mA
[11:37:46.216] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[11:37:46.216] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[11:37:46.712] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x10fcf00
[11:37:46.776] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:37:46.776] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:37:46.776] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:37:46.781] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:37:46.781] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:37:46.781] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:37:46.794] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:37:46.794] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:37:46.807] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:37:46.807] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:37:46.824] INFO: PixTestCmd::init()
[11:37:46.834] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:37:46.834] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:37:46.834] INFO: readGainPedestalParameters data/mp238/phCalibrationFitErr35_C0.dat .. data/mp238/phCalibrationFitErr35_C15.dat
[11:37:47.082] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:37:47.082] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:37:47.096] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:37:47.102] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:37:47.102] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:37:47.102] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:37:47.121] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:37:47.122] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:37:47.122] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:37:47.122] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:37:47.122] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:37:47.127] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:37:47.127] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:37:47.142] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:37:47.142] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:37:47.142] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:37:47.142] INFO: readReadbackCal: data/mp238/readbackCal_C0.dat .. data/mp238/readbackCal_C15.dat
[11:37:47.152] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:37:47.159] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:37:47.159] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:37:47.159] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:37:47.159] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:37:47.173] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:37:47.174] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:37:57.434] INFO: ######################################################################
[11:37:57.434] INFO: PixTestAlive::doTest()
[11:37:57.434] INFO: ######################################################################
[11:37:57.437] INFO: ----------------------------------------------------------------------
[11:37:57.437] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:37:57.437] INFO: ----------------------------------------------------------------------
[11:37:57.440] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:37:57.818] INFO: Expecting 41600 events.
[11:38:02.180] INFO: 41600 events read in total (3644ms).
[11:38:02.328] INFO: Test took 4888ms.
[11:38:02.338] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:02.338] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[11:38:02.338] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[11:38:02.615] INFO: PixTestAlive::aliveTest() done
[11:38:02.615] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:38:02.615] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 59 28 38 96 71 73 69 60 73 77 95 81 64 54 27 21
[11:38:02.642] INFO: ----------------------------------------------------------------------
[11:38:02.642] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:38:02.642] INFO: ----------------------------------------------------------------------
[11:38:02.645] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:38:03.028] INFO: Expecting 41600 events.
[11:38:06.139] INFO: 41600 events read in total (2396ms).
[11:38:06.139] INFO: Test took 3494ms.
[11:38:06.139] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:06.139] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[11:38:06.139] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[11:38:06.140] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:38:06.562] INFO: PixTestAlive::maskTest() done
[11:38:06.562] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:38:06.584] INFO: ----------------------------------------------------------------------
[11:38:06.584] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:38:06.584] INFO: ----------------------------------------------------------------------
[11:38:06.586] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:38:06.964] INFO: Expecting 41600 events.
[11:38:11.347] INFO: 41600 events read in total (3668ms).
[11:38:11.348] INFO: Test took 4762ms.
[11:38:11.359] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:11.359] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[11:38:11.359] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[11:38:11.730] INFO: PixTestAlive::addressDecodingTest() done
[11:38:11.730] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:38:11.730] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[11:38:18.535] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:38:18.535] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[11:38:22.567] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[11:38:22.567] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[11:38:22.567] INFO: ----------------------------------------------------------------------
[11:38:22.567] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:38:22.567] INFO: ----------------------------------------------------------------------
[11:38:22.706] INFO: Expecting 768 events.
[11:38:23.838] INFO: 768 events read in total (417ms).
[11:38:23.839] INFO: Test took 1267ms.
[11:38:23.845] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:38:24.642] INFO: Expecting 41600 events.
[11:38:28.512] INFO: 41600 events read in total (3343ms).
[11:38:28.518] INFO: Test took 4673ms.
[11:38:28.677] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:28.677] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 877707
[11:38:28.677] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[11:38:28.678] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:38:28.742] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:38:29.413] INFO: Expecting 41600 events.
[11:38:33.384] INFO: 41600 events read in total (3444ms).
[11:38:33.390] INFO: Test took 4648ms.
[11:38:33.537] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:33.537] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 887654
[11:38:33.537] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[11:38:33.537] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:38:33.605] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:38:34.272] INFO: Expecting 41600 events.
[11:38:38.304] INFO: 41600 events read in total (3505ms).
[11:38:38.310] INFO: Test took 4705ms.
[11:38:38.460] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:38.460] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 887506
[11:38:38.460] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[11:38:38.460] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:38:38.529] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:38:39.200] INFO: Expecting 41600 events.
[11:38:43.187] INFO: 41600 events read in total (3460ms).
[11:38:43.192] INFO: Test took 4663ms.
[11:38:43.337] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:43.337] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 889668
[11:38:43.337] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[11:38:43.338] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:38:43.405] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:38:44.071] INFO: Expecting 41600 events.
[11:38:48.068] INFO: 41600 events read in total (3470ms).
[11:38:48.073] INFO: Test took 4668ms.
[11:38:48.219] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:48.219] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 888235
[11:38:48.219] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[11:38:48.219] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:38:48.286] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:38:48.951] INFO: Expecting 41600 events.
[11:38:52.961] INFO: 41600 events read in total (3483ms).
[11:38:52.967] INFO: Test took 4681ms.
[11:38:53.130] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:53.130] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 889844
[11:38:53.130] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[11:38:53.130] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:38:53.199] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:38:53.876] INFO: Expecting 41600 events.
[11:38:57.954] INFO: 41600 events read in total (3551ms).
[11:38:57.960] INFO: Test took 4761ms.
[11:38:58.107] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:38:58.107] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 888497
[11:38:58.107] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[11:38:58.108] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:38:58.175] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:38:58.843] INFO: Expecting 41600 events.
[11:39:02.922] INFO: 41600 events read in total (3553ms).
[11:39:02.928] INFO: Test took 4753ms.
[11:39:03.078] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:03.078] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 888974
[11:39:03.078] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[11:39:03.079] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:39:03.146] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:39:03.813] INFO: Expecting 41600 events.
[11:39:07.838] INFO: 41600 events read in total (3498ms).
[11:39:07.843] INFO: Test took 4697ms.
[11:39:07.988] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:07.988] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 890414
[11:39:07.988] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[11:39:07.989] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:39:08.056] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:39:08.722] INFO: Expecting 41600 events.
[11:39:12.759] INFO: 41600 events read in total (3510ms).
[11:39:12.765] INFO: Test took 4709ms.
[11:39:12.918] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:12.918] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 889707
[11:39:12.918] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[11:39:12.919] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:39:12.988] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:39:13.653] INFO: Expecting 41600 events.
[11:39:17.697] INFO: 41600 events read in total (3517ms).
[11:39:17.703] INFO: Test took 4715ms.
[11:39:17.848] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:17.848] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 889684
[11:39:17.848] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[11:39:17.848] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:39:17.916] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:39:18.583] INFO: Expecting 41600 events.
[11:39:22.635] INFO: 41600 events read in total (3526ms).
[11:39:22.640] INFO: Test took 4724ms.
[11:39:22.785] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:22.785] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 890814
[11:39:22.785] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[11:39:22.785] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:39:22.853] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:39:23.522] INFO: Expecting 41600 events.
[11:39:27.531] INFO: 41600 events read in total (3482ms).
[11:39:27.537] INFO: Test took 4684ms.
[11:39:27.682] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:27.682] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 890086
[11:39:27.682] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[11:39:27.683] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:39:27.750] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:39:28.417] INFO: Expecting 41600 events.
[11:39:32.482] INFO: 41600 events read in total (3538ms).
[11:39:32.488] INFO: Test took 4738ms.
[11:39:32.640] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:32.640] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 888588
[11:39:32.640] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[11:39:32.641] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:39:32.709] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:39:33.377] INFO: Expecting 41600 events.
[11:39:37.443] INFO: 41600 events read in total (3539ms).
[11:39:37.449] INFO: Test took 4740ms.
[11:39:37.598] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:37.598] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 887596
[11:39:37.598] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[11:39:37.599] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:39:37.666] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:39:38.334] INFO: Expecting 41600 events.
[11:39:42.388] INFO: 41600 events read in total (3527ms).
[11:39:42.395] INFO: Test took 4729ms.
[11:39:42.545] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:42.545] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 886209
[11:39:42.546] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[11:39:42.546] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:39:42.614] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:39:43.287] INFO: Expecting 41600 events.
[11:39:47.310] INFO: 41600 events read in total (3496ms).
[11:39:47.316] INFO: Test took 4702ms.
[11:39:47.465] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:47.465] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 886958
[11:39:47.465] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[11:39:47.466] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:39:47.533] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:39:48.199] INFO: Expecting 41600 events.
[11:39:52.251] INFO: 41600 events read in total (3526ms).
[11:39:52.258] INFO: Test took 4725ms.
[11:39:52.405] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:52.405] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 887345
[11:39:52.405] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[11:39:52.405] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:39:52.473] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:39:53.140] INFO: Expecting 41600 events.
[11:39:57.105] INFO: 41600 events read in total (3439ms).
[11:39:57.111] INFO: Test took 4638ms.
[11:39:57.253] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:57.253] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 885849
[11:39:57.253] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[11:39:57.253] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:39:57.321] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:39:57.986] INFO: Expecting 41600 events.
[11:40:01.835] INFO: 41600 events read in total (3322ms).
[11:40:01.840] INFO: Test took 4519ms.
[11:40:01.986] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:01.986] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 881435
[11:40:01.986] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[11:40:01.986] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:40:02.275] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 164 eff = 0.998966
[11:40:02.275] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 140 eff = 0.999038
[11:40:02.275] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 164 eff = 0.99762
[11:40:02.275] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 154 eff = 0.995865
[11:40:02.276] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 162 eff = 0.995505
[11:40:02.276] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 168 eff = 0.996034
[11:40:02.276] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 166 eff = 0.996298
[11:40:02.276] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 162 eff = 0.996034
[11:40:02.276] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 155 eff = 0.996178
[11:40:02.277] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 171 eff = 0.995313
[11:40:02.277] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 158 eff = 0.995
[11:40:02.277] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 153 eff = 0.99524
[11:40:02.277] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 154 eff = 0.996322
[11:40:02.278] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 158 eff = 0.997668
[11:40:02.278] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 122 eff = 0.999183
[11:40:02.278] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 167 eff = 0.999207
[11:40:10.783] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[11:40:10.783] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[11:40:10.783] INFO: ----------------------------------------------------------------------
[11:40:10.783] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:40:10.783] INFO: ----------------------------------------------------------------------
[11:40:10.783] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[11:40:10.783] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[11:40:10.783] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[11:40:10.783] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[11:40:10.783] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[11:40:10.783] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[11:40:10.783] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[11:40:10.783] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[11:40:10.783] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[11:40:10.783] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[11:40:10.783] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[11:40:10.783] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[11:40:10.804] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:40:11.471] INFO: Expecting 208000 events.
[11:40:28.457] INFO: 208000 events read in total (16459ms).
[11:40:28.477] INFO: Test took 17673ms.
[11:40:29.388] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:40:29.388] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4126836
[11:40:29.388] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[11:40:29.388] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:40:29.891] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:40:29.891] INFO: number of red-efficiency pixels: 258 239 427 807 849 799 756 710 788 855 830 858 764 443 217 175
[11:40:29.891] INFO: number of X-ray hits detected: 170891 121407 199364 299380 316573 316606 313272 294524 315923 325900 322248 306853 299783 200498 118226 138828
[11:40:29.891] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:40:29.891] INFO: number of Vcal hits detected: 207726 207751 207508 207037 206994 207069 207138 207195 207119 206996 207003 206994 207115 207472 207777 207819
[11:40:29.891] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.6 99.6 99.6 99.6 99.6 99.6 99.6 99.6 99.6 99.6 99.8 99.9 99.9
[11:40:29.891] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.5 99.5 99.6 99.6 99.6 99.6 99.5 99.5 99.5 99.6 99.7 99.9 99.9
[11:40:29.891] INFO: X-ray hit rate [MHz/cm2]: 50.1 35.6 58.4 87.8 92.8 92.8 91.8 86.3 92.6 95.5 94.5 89.9 87.9 58.8 34.7 40.7
[11:40:29.891] INFO: PixTestHighRate::doXPixelAlive() done
[11:40:29.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[11:40:29.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[11:40:29.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[11:40:29.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[11:40:29.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[11:40:29.938] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[11:40:29.938] INFO: PixTest:: pg_setup set to default.
[11:40:44.174] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:40:44.175] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[11:40:46.583] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[11:40:46.583] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[11:40:46.583] INFO: ----------------------------------------------------------------------
[11:40:46.583] INFO: PixTestHighRate::runDaq() running for 2 seconds
[11:40:46.583] INFO: ----------------------------------------------------------------------
[11:40:46.787] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[11:40:46.788] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[11:40:46.788] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[11:40:46.788] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[11:40:46.788] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[11:40:46.788] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[11:40:46.788] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[11:40:46.788] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[11:40:46.788] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[11:40:46.788] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[11:40:46.788] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[11:40:46.788] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[11:40:47.745] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[11:40:49.748] INFO: data taking finished, elapsed time: 2 seconds.
[11:40:51.694] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39828 events with 771315 pixels
[11:40:51.699] INFO: PixTest:: pg_setup set to default.
[11:40:51.804] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[11:40:51.804] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[11:40:51.804] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[11:40:51.805] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[11:40:51.805] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[11:40:51.805] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[11:40:51.805] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[11:40:51.805] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[11:40:51.805] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[11:40:51.805] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[11:40:51.806] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[11:40:51.806] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[11:40:51.806] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[11:40:51.806] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[11:40:51.806] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[11:40:51.806] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[11:40:51.807] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[11:40:51.807] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[11:40:51.807] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[11:40:51.807] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[11:40:51.807] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[11:40:51.807] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[11:40:51.807] INFO: Pixels without X-ray hits (per ROC): 339 821 488 93 83 88 86 87 77 92 74 151 101 487 821 439
[11:40:51.807] INFO: PixTestHighRate::doRunDaq() done
[11:40:59.431] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:40:59.431] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C13_V0 -> hitMap_daqbbtest_mod
[11:41:04.935] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:41:04.935] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 393.9mA
[11:41:04.936] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 469.5mA
[11:41:04.936] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[11:41:04.936] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:41:04.936] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:41:04.936] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:41:04.936] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:41:04.937] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:41:04.937] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:41:04.937] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:41:04.937] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:41:04.937] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:41:04.937] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:41:05.045] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:41:05.045] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:41:05.059] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:41:05.059] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:41:05.059] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:41:05.059] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[11:41:05.059] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:41:05.059] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:41:05.062] QUIET: Connection to board 58 closed.
[11:41:05.141] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-904-gd94b9f2 on branch master