Test Date: 1973-11-29 16:33
Analysis date: 2016-09-13 19:47
Logfile
hrData_40.log
[16:32:17.659] INFO: *** Welcome to pxar ***
[16:32:17.659] INFO: *** Today: 2016/09/12
[16:32:17.683] INFO: *** Version: v1.9.0-818-g96727
[16:32:17.683] INFO: readRocDacs: data/mq818/dacParameters35_C0.dat .. data/mq818/dacParameters35_C15.dat
[16:32:17.684] INFO: readTbmDacs: data/mq818/tbmParameters_C0a.dat .. data/mq818/tbmParameters_C0b.dat
[16:32:17.684] INFO: readMaskFile: data/mq818/defaultMaskFile.dat
[16:32:17.684] INFO: readTrimFile: data/mq818/trimParameters35_C0.dat .. data/mq818/trimParameters35_C15.dat
[16:32:17.833] INFO: clk: 4
[16:32:17.833] INFO: ctr: 4
[16:32:17.833] INFO: sda: 19
[16:32:17.833] INFO: tin: 9
[16:32:17.833] INFO: level: 15
[16:32:17.833] INFO: triggerdelay: 0
[16:32:17.834] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:32:17.834] INFO: Log level: DEBUG
[16:32:17.844] QUIET: Connection to board DTB_WRPRHI opened.
[16:32:17.848] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:32:17.851] INFO: RPC call hashes of host and DTB match: 398089610
[16:32:19.453] INFO: DUT info:
[16:32:19.453] INFO: The DUT currently contains the following objects:
[16:32:19.453] INFO: 2 TBM Cores tbm08c (2 ON)
[16:32:19.453] INFO: TBM Core alpha (0): 7 registers set
[16:32:19.453] INFO: TBM Core beta (1): 7 registers set
[16:32:19.453] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:32:19.453] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.453] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.453] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.453] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.453] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.453] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.453] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.453] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.453] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.453] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.453] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.453] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.453] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.453] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.453] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.453] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:32:19.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:32:19.455] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:32:19.456] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:32:19.458] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31375360
[16:32:19.458] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1eb80e0
[16:32:19.458] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1c5a370
[16:32:19.458] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fdf7dd94010
[16:32:19.458] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fdf83fff510
[16:32:19.458] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31383552 fPxarMemory = 0x7fdf7dd94010
[16:32:19.459] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[16:32:19.461] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[16:32:19.461] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:32:19.957] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x22a6f70
[16:32:20.020] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:32:20.020] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:32:20.020] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:32:20.026] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:32:20.026] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:32:20.026] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:32:20.040] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:32:20.040] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:32:20.077] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:32:20.077] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:32:20.097] INFO: PixTestCmd::init()
[16:32:20.102] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:32:20.102] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:32:20.102] INFO: readGainPedestalParameters data/mq818/phCalibrationFitErr35_C0.dat .. data/mq818/phCalibrationFitErr35_C15.dat
[16:32:20.348] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:32:20.348] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:32:20.361] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:32:20.368] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:32:20.368] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:32:20.368] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:32:20.390] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:32:20.390] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:32:20.390] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:32:20.390] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:32:20.390] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:32:20.396] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:32:20.397] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:32:20.413] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:32:20.413] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:32:20.413] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:32:20.413] INFO: readReadbackCal: data/mq818/readbackCal_C0.dat .. data/mq818/readbackCal_C15.dat
[16:32:20.423] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:32:20.431] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:32:20.431] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:32:20.431] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:32:20.431] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:32:20.450] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:32:20.450] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:32:23.844] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[16:32:26.697] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[16:32:26.697] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[16:32:26.697] INFO: ----------------------------------------------------------------------
[16:32:26.697] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[16:32:26.697] INFO: ----------------------------------------------------------------------
[16:32:27.672] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[16:32:41.127] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[16:32:41.131] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:33:09.091] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1325719 events.
[16:33:13.949] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1325719, pixels seen in all events: 4343629
[16:33:14.061] INFO: Resuming triggers.
[16:33:27.528] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[16:33:27.571] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:33:55.286] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1326702 events.
[16:34:00.121] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1326702, pixels seen in all events: 4330513
[16:34:00.332] INFO: Resuming triggers.
[16:34:13.795] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[16:34:13.830] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:34:41.480] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1326370 events.
[16:34:46.300] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1326370, pixels seen in all events: 4334667
[16:34:46.503] INFO: Resuming triggers.
[16:34:59.974] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[16:35:00.007] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:35:27.751] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1327058 events.
[16:35:32.597] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1327058, pixels seen in all events: 4326310
[16:35:32.745] INFO: Resuming triggers.
[16:35:38.968] INFO: data taking finished, elapsed time: 60 seconds.
[16:35:39.164] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:35:52.045] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 613042 events.
[16:35:54.268] DEBUG: <PixTestXray.cc/processData:L823> # events read: 613042, pixels seen in all events: 1999228
[16:35:54.335] INFO: PixTest:: pg_setup set to default.
[16:35:54.391] INFO: PixTestXray::doPhRun() done
[16:36:25.593] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:36:25.593] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hMap_Ag_C12_V0 -> hMap_Ag_mod
[16:36:27.177] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:36:27.178] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 383.5mA
[16:36:27.179] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 465.5mA
[16:36:27.179] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:36:27.179] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:36:27.179] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:36:27.179] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:36:27.179] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:36:27.179] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:36:27.179] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:36:27.179] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:36:27.179] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:36:27.180] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:36:27.180] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:36:27.180] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:36:27.180] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:36:27.180] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:36:27.180] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:36:27.180] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:36:27.180] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:36:27.180] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:36:27.180] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:36:27.460] QUIET: Connection to board 58 closed.
[16:36:27.540] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[16:36:37.393] INFO: *** Welcome to pxar ***
[16:36:37.393] INFO: *** Today: 2016/09/12
[16:36:37.421] INFO: *** Version: v1.9.0-818-g96727
[16:36:37.421] INFO: readRocDacs: data/mq818/dacParameters35_C0.dat .. data/mq818/dacParameters35_C15.dat
[16:36:37.422] INFO: readTbmDacs: data/mq818/tbmParameters_C0a.dat .. data/mq818/tbmParameters_C0b.dat
[16:36:37.422] INFO: readMaskFile: data/mq818/defaultMaskFile.dat
[16:36:37.422] INFO: readTrimFile: data/mq818/trimParameters35_C0.dat .. data/mq818/trimParameters35_C15.dat
[16:36:37.573] INFO: clk: 4
[16:36:37.573] INFO: ctr: 4
[16:36:37.573] INFO: sda: 19
[16:36:37.573] INFO: tin: 9
[16:36:37.573] INFO: level: 15
[16:36:37.573] INFO: triggerdelay: 0
[16:36:37.573] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:36:37.573] INFO: Log level: DEBUG
[16:36:37.584] QUIET: Connection to board DTB_WRPRHI opened.
[16:36:37.587] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:36:37.590] INFO: RPC call hashes of host and DTB match: 398089610
[16:36:39.191] INFO: DUT info:
[16:36:39.191] INFO: The DUT currently contains the following objects:
[16:36:39.191] INFO: 2 TBM Cores tbm08c (2 ON)
[16:36:39.192] INFO: TBM Core alpha (0): 7 registers set
[16:36:39.192] INFO: TBM Core beta (1): 7 registers set
[16:36:39.192] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:36:39.192] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:39.192] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:36:39.192] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:36:39.192] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:36:39.192] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:36:39.193] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:36:39.194] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:36:39.195] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:36:39.197] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31379456
[16:36:39.197] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x148b0e0
[16:36:39.197] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x122d370
[16:36:39.197] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fb1a1d94010
[16:36:39.197] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fb1a7fff510
[16:36:39.197] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31387648 fPxarMemory = 0x7fb1a1d94010
[16:36:39.199] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[16:36:39.200] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[16:36:39.200] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:36:39.695] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x187a030
[16:36:39.756] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:36:39.757] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:36:39.757] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:36:39.762] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:36:39.762] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:36:39.762] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:36:39.776] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:36:39.776] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:36:39.781] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:36:39.781] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:36:39.803] INFO: PixTestCmd::init()
[16:36:39.814] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:36:39.814] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:36:39.815] INFO: readGainPedestalParameters data/mq818/phCalibrationFitErr35_C0.dat .. data/mq818/phCalibrationFitErr35_C15.dat
[16:36:40.066] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:36:40.066] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:36:40.078] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:36:40.087] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:36:40.087] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:36:40.087] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:36:40.109] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:36:40.109] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:36:40.109] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:36:40.109] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:36:40.109] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:36:40.115] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:36:40.115] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:36:40.129] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:36:40.129] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:36:40.129] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:36:40.129] INFO: readReadbackCal: data/mq818/readbackCal_C0.dat .. data/mq818/readbackCal_C15.dat
[16:36:40.139] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:36:40.146] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:36:40.146] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:36:40.146] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:36:40.146] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:36:40.163] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:36:40.163] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:36:46.500] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[16:36:48.681] INFO: ######################################################################
[16:36:48.681] INFO: PixTestXray::doTest()
[16:36:48.681] INFO: ######################################################################
[16:36:48.682] INFO: ----------------------------------------------------------------------
[16:36:48.682] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[16:36:48.682] INFO: ----------------------------------------------------------------------
[16:36:49.655] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[16:36:57.573] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[16:36:57.577] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:37:22.875] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 780136 events.
[16:37:34.719] DEBUG: <PixTestXray.cc/processData:L823> # events read: 780136, pixels seen in all events: 10922642
[16:37:34.977] INFO: Resuming triggers.
[16:37:42.910] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[16:37:42.948] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:38:08.336] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 781403 events.
[16:38:20.198] DEBUG: <PixTestXray.cc/processData:L823> # events read: 781403, pixels seen in all events: 10874986
[16:38:20.409] INFO: Resuming triggers.
[16:38:28.347] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[16:38:28.392] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:38:53.604] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 781979 events.
[16:39:05.562] DEBUG: <PixTestXray.cc/processData:L823> # events read: 781979, pixels seen in all events: 10868723
[16:39:05.769] INFO: Resuming triggers.
[16:39:13.708] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[16:39:13.781] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:39:39.076] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 782106 events.
[16:39:50.942] DEBUG: <PixTestXray.cc/processData:L823> # events read: 782106, pixels seen in all events: 10866161
[16:39:51.170] INFO: Resuming triggers.
[16:39:59.111] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[16:39:59.193] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:40:24.386] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 782265 events.
[16:40:36.181] DEBUG: <PixTestXray.cc/processData:L823> # events read: 782265, pixels seen in all events: 10865090
[16:40:36.350] INFO: Resuming triggers.
[16:40:44.292] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[16:40:44.336] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:41:09.567] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 782375 events.
[16:41:21.602] DEBUG: <PixTestXray.cc/processData:L823> # events read: 782375, pixels seen in all events: 10863406
[16:41:21.814] INFO: Resuming triggers.
[16:41:29.757] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[16:41:29.830] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:41:55.109] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 782486 events.
[16:42:06.902] DEBUG: <PixTestXray.cc/processData:L823> # events read: 782486, pixels seen in all events: 10861966
[16:42:07.150] INFO: Resuming triggers.
[16:42:11.669] INFO: data taking finished, elapsed time: 60 seconds.
[16:42:11.865] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:42:26.321] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 445177 events.
[16:42:33.054] DEBUG: <PixTestXray.cc/processData:L823> # events read: 445177, pixels seen in all events: 6174836
[16:42:33.168] INFO: PixTest:: pg_setup set to default.
[16:42:33.210] INFO: PixTestXray::doPhRun() done
[16:42:33.210] INFO: PixTestXray::doTest() done
[16:42:41.722] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:42:41.722] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hMap_Ag_C13_V0 -> hMap_Ag_mod
[16:42:42.754] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:42:42.754] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[16:42:42.755] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.1mA
[16:42:42.755] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:42:42.755] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:42:42.755] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:42:42.755] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:42:42.755] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:42:42.756] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:42:42.756] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:42:42.756] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:42:42.756] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:42:42.756] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:42:42.756] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:42:42.756] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:42:42.756] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:42:42.756] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:42:42.756] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:42:42.756] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:42:42.756] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:42:42.756] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:42:42.756] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:42:43.075] QUIET: Connection to board 58 closed.
[16:42:43.154] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[16:27:34.164] INFO: *** Welcome to pxar ***
[16:27:34.164] INFO: *** Today: 2016/09/12
[16:27:34.188] INFO: *** Version: v1.9.0-818-g96727
[16:27:34.188] INFO: readRocDacs: data/mq818/dacParameters35_C0.dat .. data/mq818/dacParameters35_C15.dat
[16:27:34.189] INFO: readTbmDacs: data/mq818/tbmParameters_C0a.dat .. data/mq818/tbmParameters_C0b.dat
[16:27:34.190] INFO: readMaskFile: data/mq818/defaultMaskFile.dat
[16:27:34.190] INFO: readTrimFile: data/mq818/trimParameters35_C0.dat .. data/mq818/trimParameters35_C15.dat
[16:27:34.339] INFO: clk: 4
[16:27:34.339] INFO: ctr: 4
[16:27:34.339] INFO: sda: 19
[16:27:34.339] INFO: tin: 9
[16:27:34.339] INFO: level: 15
[16:27:34.339] INFO: triggerdelay: 0
[16:27:34.339] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:27:34.339] INFO: Log level: DEBUG
[16:27:34.350] QUIET: Connection to board DTB_WRPRHI opened.
[16:27:34.354] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:27:34.357] INFO: RPC call hashes of host and DTB match: 398089610
[16:27:35.959] INFO: DUT info:
[16:27:35.959] INFO: The DUT currently contains the following objects:
[16:27:35.959] INFO: 2 TBM Cores tbm08c (2 ON)
[16:27:35.959] INFO: TBM Core alpha (0): 7 registers set
[16:27:35.959] INFO: TBM Core beta (1): 7 registers set
[16:27:35.959] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:27:35.959] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.959] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.959] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.959] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.959] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.959] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.959] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.960] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.960] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.960] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.960] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.960] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.960] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.960] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.960] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.960] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:27:35.960] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:27:35.961] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:27:35.962] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:27:35.963] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:27:35.965] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32419840
[16:27:35.965] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xc920e0
[16:27:35.965] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xa34370
[16:27:35.965] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fab75393010
[16:27:35.965] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fab7b5fe510
[16:27:35.966] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32428032 fPxarMemory = 0x7fab75393010
[16:27:35.967] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 380.2mA
[16:27:35.968] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[16:27:35.968] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:27:36.466] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1080fd0
[16:27:36.530] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:27:36.530] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:27:36.531] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:27:36.537] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:27:36.537] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:27:36.537] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:27:36.549] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:27:36.549] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:27:36.570] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:27:36.570] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:27:36.579] INFO: PixTestCmd::init()
[16:27:36.601] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:27:36.601] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:27:36.601] INFO: readGainPedestalParameters data/mq818/phCalibrationFitErr35_C0.dat .. data/mq818/phCalibrationFitErr35_C15.dat
[16:27:36.852] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:27:36.852] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:27:36.867] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:27:36.872] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:27:36.873] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:27:36.873] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:27:36.895] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:27:36.895] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:27:36.895] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:27:36.895] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:27:36.895] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:27:36.902] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:27:36.902] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:27:36.921] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:27:36.921] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:27:36.921] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:27:36.921] INFO: readReadbackCal: data/mq818/readbackCal_C0.dat .. data/mq818/readbackCal_C15.dat
[16:27:36.931] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:27:36.939] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:27:36.939] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:27:36.939] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:27:36.939] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:27:36.956] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:27:36.956] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:27:39.740] INFO: ######################################################################
[16:27:39.740] INFO: PixTestAlive::doTest()
[16:27:39.740] INFO: ######################################################################
[16:27:39.744] INFO: ----------------------------------------------------------------------
[16:27:39.744] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:27:39.744] INFO: ----------------------------------------------------------------------
[16:27:39.747] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:27:40.122] INFO: Expecting 41600 events.
[16:27:44.513] INFO: 41600 events read in total (3673ms).
[16:27:44.659] INFO: Test took 4912ms.
[16:27:44.670] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:44.670] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:27:44.670] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:27:44.950] INFO: PixTestAlive::aliveTest() done
[16:27:44.950] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:27:44.950] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 17 16 26 39 32 44 36 42 32 43 39 46 41 31 15 20
[16:27:44.981] INFO: ----------------------------------------------------------------------
[16:27:44.981] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:27:44.981] INFO: ----------------------------------------------------------------------
[16:27:44.983] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:27:45.364] INFO: Expecting 41600 events.
[16:27:48.479] INFO: 41600 events read in total (2400ms).
[16:27:48.479] INFO: Test took 3496ms.
[16:27:48.479] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:48.479] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:27:48.480] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:27:48.480] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:27:48.907] INFO: PixTestAlive::maskTest() done
[16:27:48.907] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:27:48.931] INFO: ----------------------------------------------------------------------
[16:27:48.931] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:27:48.931] INFO: ----------------------------------------------------------------------
[16:27:48.933] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:27:49.310] INFO: Expecting 41600 events.
[16:27:53.688] INFO: 41600 events read in total (3663ms).
[16:27:53.691] INFO: Test took 4758ms.
[16:27:53.700] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:53.700] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:27:53.700] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:27:54.071] INFO: PixTestAlive::addressDecodingTest() done
[16:27:54.071] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:27:54.071] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[16:27:55.897] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:27:55.897] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:27:55.897] INFO: ----------------------------------------------------------------------
[16:27:55.897] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:27:55.898] INFO: ----------------------------------------------------------------------
[16:27:56.037] INFO: Expecting 768 events.
[16:27:57.171] INFO: 768 events read in total (419ms).
[16:27:57.171] INFO: Test took 1268ms.
[16:27:57.177] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:27:57.975] INFO: Expecting 41600 events.
[16:28:01.279] INFO: 41600 events read in total (2777ms).
[16:28:01.282] INFO: Test took 4105ms.
[16:28:01.362] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:01.362] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 474942
[16:28:01.362] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:28:01.362] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:01.401] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:02.066] INFO: Expecting 41600 events.
[16:28:05.531] INFO: 41600 events read in total (2938ms).
[16:28:05.535] INFO: Test took 4134ms.
[16:28:05.616] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:05.616] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 485725
[16:28:05.616] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:28:05.616] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:05.656] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:06.321] INFO: Expecting 41600 events.
[16:28:09.842] INFO: 41600 events read in total (2994ms).
[16:28:09.846] INFO: Test took 4190ms.
[16:28:09.932] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:09.932] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 490068
[16:28:09.932] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:28:09.932] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:09.972] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:10.637] INFO: Expecting 41600 events.
[16:28:14.170] INFO: 41600 events read in total (3006ms).
[16:28:14.174] INFO: Test took 4202ms.
[16:28:14.257] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:14.257] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 489241
[16:28:14.257] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:28:14.258] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:14.300] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:14.967] INFO: Expecting 41600 events.
[16:28:18.514] INFO: 41600 events read in total (3020ms).
[16:28:18.518] INFO: Test took 4218ms.
[16:28:18.600] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:18.600] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 491063
[16:28:18.600] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:28:18.600] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:18.642] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:19.308] INFO: Expecting 41600 events.
[16:28:22.837] INFO: 41600 events read in total (3002ms).
[16:28:22.841] INFO: Test took 4199ms.
[16:28:22.923] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:22.923] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 490021
[16:28:22.923] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:28:22.923] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:22.965] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:23.631] INFO: Expecting 41600 events.
[16:28:27.166] INFO: 41600 events read in total (3008ms).
[16:28:27.170] INFO: Test took 4205ms.
[16:28:27.251] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:27.251] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 489313
[16:28:27.251] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:28:27.252] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:27.294] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:27.960] INFO: Expecting 41600 events.
[16:28:31.502] INFO: 41600 events read in total (3015ms).
[16:28:31.505] INFO: Test took 4211ms.
[16:28:31.586] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:31.586] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 490374
[16:28:31.586] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:28:31.586] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:31.627] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:32.292] INFO: Expecting 41600 events.
[16:28:35.837] INFO: 41600 events read in total (3018ms).
[16:28:35.841] INFO: Test took 4214ms.
[16:28:35.922] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:35.922] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 490979
[16:28:35.922] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:28:35.923] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:35.965] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:36.631] INFO: Expecting 41600 events.
[16:28:40.176] INFO: 41600 events read in total (3018ms).
[16:28:40.180] INFO: Test took 4215ms.
[16:28:40.260] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:40.260] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 489078
[16:28:40.260] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:28:40.260] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:40.301] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:40.966] INFO: Expecting 41600 events.
[16:28:44.505] INFO: 41600 events read in total (3012ms).
[16:28:44.508] INFO: Test took 4207ms.
[16:28:44.588] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:44.588] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 490020
[16:28:44.588] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:28:44.589] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:44.629] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:45.294] INFO: Expecting 41600 events.
[16:28:48.841] INFO: 41600 events read in total (3020ms).
[16:28:48.844] INFO: Test took 4215ms.
[16:28:48.925] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:48.925] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 489672
[16:28:48.925] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:28:48.925] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:48.966] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:49.631] INFO: Expecting 41600 events.
[16:28:53.176] INFO: 41600 events read in total (3018ms).
[16:28:53.180] INFO: Test took 4214ms.
[16:28:53.261] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:53.261] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 489202
[16:28:53.261] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:28:53.262] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:53.304] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:53.970] INFO: Expecting 41600 events.
[16:28:57.519] INFO: 41600 events read in total (3022ms).
[16:28:57.523] INFO: Test took 4219ms.
[16:28:57.604] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:57.604] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 490490
[16:28:57.604] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:28:57.604] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:57.646] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:58.313] INFO: Expecting 41600 events.
[16:29:01.830] INFO: 41600 events read in total (2991ms).
[16:29:01.834] INFO: Test took 4188ms.
[16:29:01.915] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:01.915] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 489437
[16:29:01.915] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:29:01.915] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:29:01.957] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:29:02.624] INFO: Expecting 41600 events.
[16:29:06.170] INFO: 41600 events read in total (3020ms).
[16:29:06.174] INFO: Test took 4217ms.
[16:29:06.255] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:06.255] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 489191
[16:29:06.255] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:29:06.256] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:29:06.296] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:29:06.961] INFO: Expecting 41600 events.
[16:29:10.507] INFO: 41600 events read in total (3019ms).
[16:29:10.511] INFO: Test took 4215ms.
[16:29:10.591] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:10.592] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 489547
[16:29:10.592] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:29:10.592] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:29:10.632] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:29:11.297] INFO: Expecting 41600 events.
[16:29:14.840] INFO: 41600 events read in total (3016ms).
[16:29:14.844] INFO: Test took 4212ms.
[16:29:14.925] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:14.925] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 490512
[16:29:14.925] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:29:14.925] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:29:14.967] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:29:15.634] INFO: Expecting 41600 events.
[16:29:19.153] INFO: 41600 events read in total (2992ms).
[16:29:19.157] INFO: Test took 4190ms.
[16:29:19.241] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:19.241] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 489070
[16:29:19.241] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:29:19.241] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:29:19.284] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:29:19.951] INFO: Expecting 41600 events.
[16:29:23.343] INFO: 41600 events read in total (2866ms).
[16:29:23.346] INFO: Test took 4062ms.
[16:29:23.428] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:23.428] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483146
[16:29:23.428] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:29:23.428] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:29:23.691] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 162 eff = 0.999663
[16:29:23.691] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 145 eff = 0.999712
[16:29:23.691] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 152 eff = 0.999519
[16:29:23.691] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 148 eff = 0.999087
[16:29:23.692] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 148 eff = 0.99863
[16:29:23.692] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 151 eff = 0.998774
[16:29:23.692] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 151 eff = 0.998798
[16:29:23.692] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 148 eff = 0.998726
[16:29:23.693] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 191 eff = 0.998918
[16:29:23.693] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 155 eff = 0.99863
[16:29:23.693] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 135 eff = 0.998678
[16:29:23.693] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 152 eff = 0.99887
[16:29:23.694] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 149 eff = 0.998846
[16:29:23.694] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 153 eff = 0.999279
[16:29:23.694] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 152 eff = 0.999784
[16:29:23.694] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 134 eff = 0.999567
[16:29:24.169] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:29:24.169] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:29:24.170] INFO: ----------------------------------------------------------------------
[16:29:24.170] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:29:24.170] INFO: ----------------------------------------------------------------------
[16:29:24.170] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:29:24.170] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:29:24.170] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:29:24.170] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:29:24.170] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:29:24.170] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:29:24.170] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:29:24.170] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:29:24.170] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:29:24.170] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:29:24.170] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:29:24.170] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:29:24.188] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:29:24.854] INFO: Expecting 208000 events.
[16:29:38.451] INFO: 208000 events read in total (13070ms).
[16:29:38.463] INFO: Test took 14275ms.
[16:29:38.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:38.850] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2167287
[16:29:38.850] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:29:38.850] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:29:39.229] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:29:39.229] INFO: number of red-efficiency pixels: 111 93 171 235 262 255 266 226 219 295 288 255 252 165 115 107
[16:29:39.229] INFO: number of X-ray hits detected: 81722 60239 99647 150305 162526 157204 159486 152395 170020 177875 170687 156165 156203 104061 63808 78384
[16:29:39.229] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:29:39.229] INFO: number of Vcal hits detected: 207887 207883 207821 207753 207724 207731 207727 207764 207776 207696 207703 207731 207734 207826 207879 207890
[16:29:39.229] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9
[16:29:39.229] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9
[16:29:39.229] INFO: X-ray hit rate [MHz/cm2]: 24.0 17.7 29.2 44.1 47.6 46.1 46.7 44.7 49.8 52.1 50.0 45.8 45.8 30.5 18.7 23.0
[16:29:39.229] INFO: PixTestHighRate::doXPixelAlive() done
[16:29:39.275] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:29:39.275] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:29:39.275] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:29:39.275] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:29:39.275] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:29:39.275] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:29:39.275] INFO: PixTest:: pg_setup set to default.
[16:29:41.257] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:29:41.257] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[16:29:42.385] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:29:42.386] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 383.5mA
[16:29:42.387] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[16:29:42.387] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:29:42.387] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:29:42.387] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:29:42.387] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:29:42.387] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:29:42.387] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:29:42.387] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:29:42.387] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:29:42.387] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:29:42.387] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:29:42.387] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:29:42.477] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:29:42.477] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:29:42.486] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:29:42.486] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:29:42.486] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:29:42.487] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:29:42.487] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:29:42.487] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:29:42.489] QUIET: Connection to board 58 closed.
[16:29:42.568] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[16:24:55.733] INFO: *** Welcome to pxar ***
[16:24:55.733] INFO: *** Today: 2016/09/12
[16:24:55.758] INFO: *** Version: v1.9.0-818-g96727
[16:24:55.758] INFO: readRocDacs: data/mq818/dacParameters35_C0.dat .. data/mq818/dacParameters35_C15.dat
[16:24:55.759] INFO: readTbmDacs: data/mq818/tbmParameters_C0a.dat .. data/mq818/tbmParameters_C0b.dat
[16:24:55.759] INFO: readMaskFile: data/mq818/defaultMaskFile.dat
[16:24:55.759] INFO: readTrimFile: data/mq818/trimParameters35_C0.dat .. data/mq818/trimParameters35_C15.dat
[16:24:55.913] INFO: clk: 4
[16:24:55.913] INFO: ctr: 4
[16:24:55.913] INFO: sda: 19
[16:24:55.913] INFO: tin: 9
[16:24:55.913] INFO: level: 15
[16:24:55.913] INFO: triggerdelay: 0
[16:24:55.913] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:24:55.913] INFO: Log level: DEBUG
[16:24:55.924] QUIET: Connection to board DTB_WRPRHI opened.
[16:24:55.927] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:24:55.929] INFO: RPC call hashes of host and DTB match: 398089610
[16:24:57.531] INFO: DUT info:
[16:24:57.531] INFO: The DUT currently contains the following objects:
[16:24:57.531] INFO: 2 TBM Cores tbm08c (2 ON)
[16:24:57.531] INFO: TBM Core alpha (0): 7 registers set
[16:24:57.531] INFO: TBM Core beta (1): 7 registers set
[16:24:57.531] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:24:57.531] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.531] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.531] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.531] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.531] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.531] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.531] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.531] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.532] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.532] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.532] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.532] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.532] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.532] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.532] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.532] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:24:57.532] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:24:57.533] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:24:57.534] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:24:57.535] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:24:57.537] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30330880
[16:24:57.538] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x17230e0
[16:24:57.538] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x14c5370
[16:24:57.538] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f2b59d94010
[16:24:57.538] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f2b5ffff510
[16:24:57.538] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30339072 fPxarMemory = 0x7f2b59d94010
[16:24:57.539] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[16:24:57.540] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[16:24:57.540] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[16:24:58.034] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1b11f80
[16:24:58.097] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:24:58.097] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:24:58.097] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:24:58.103] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:24:58.103] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:24:58.103] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:24:58.113] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:24:58.113] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:24:58.128] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:24:58.128] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:24:58.142] INFO: PixTestCmd::init()
[16:24:58.150] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:24:58.151] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:24:58.151] INFO: readGainPedestalParameters data/mq818/phCalibrationFitErr35_C0.dat .. data/mq818/phCalibrationFitErr35_C15.dat
[16:24:58.398] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:24:58.398] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:24:58.414] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:24:58.421] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:24:58.421] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:24:58.422] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:24:58.449] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:24:58.449] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:24:58.449] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:24:58.449] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:24:58.449] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:24:58.457] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:24:58.457] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:24:58.476] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:24:58.476] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:24:58.476] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:24:58.476] INFO: readReadbackCal: data/mq818/readbackCal_C0.dat .. data/mq818/readbackCal_C15.dat
[16:24:58.485] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:24:58.493] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:24:58.493] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:24:58.493] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:24:58.493] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:24:58.509] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:24:58.509] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:25:01.932] INFO: ######################################################################
[16:25:01.932] INFO: PixTestAlive::doTest()
[16:25:01.932] INFO: ######################################################################
[16:25:01.936] INFO: ----------------------------------------------------------------------
[16:25:01.936] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:25:01.936] INFO: ----------------------------------------------------------------------
[16:25:01.938] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:02.319] INFO: Expecting 41600 events.
[16:25:06.718] INFO: 41600 events read in total (3681ms).
[16:25:06.866] INFO: Test took 4928ms.
[16:25:06.880] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:06.880] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:25:06.880] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:25:07.154] INFO: PixTestAlive::aliveTest() done
[16:25:07.154] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:25:07.154] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 30 30 33 59 52 73 60 65 65 61 65 60 72 45 20 26
[16:25:07.180] INFO: ----------------------------------------------------------------------
[16:25:07.180] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:25:07.180] INFO: ----------------------------------------------------------------------
[16:25:07.186] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:07.568] INFO: Expecting 41600 events.
[16:25:10.684] INFO: 41600 events read in total (2401ms).
[16:25:10.684] INFO: Test took 3498ms.
[16:25:10.684] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:10.684] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:25:10.684] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:25:10.685] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:25:11.107] INFO: PixTestAlive::maskTest() done
[16:25:11.107] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:25:11.130] INFO: ----------------------------------------------------------------------
[16:25:11.130] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:25:11.130] INFO: ----------------------------------------------------------------------
[16:25:11.132] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:11.509] INFO: Expecting 41600 events.
[16:25:15.888] INFO: 41600 events read in total (3664ms).
[16:25:15.890] INFO: Test took 4758ms.
[16:25:15.901] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:15.901] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:25:15.901] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:25:16.271] INFO: PixTestAlive::addressDecodingTest() done
[16:25:16.271] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:25:16.271] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[16:25:19.633] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:25:19.633] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:25:19.633] INFO: ----------------------------------------------------------------------
[16:25:19.633] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:25:19.633] INFO: ----------------------------------------------------------------------
[16:25:19.773] INFO: Expecting 768 events.
[16:25:20.907] INFO: 768 events read in total (419ms).
[16:25:20.907] INFO: Test took 1268ms.
[16:25:20.913] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:21.710] INFO: Expecting 41600 events.
[16:25:25.313] INFO: 41600 events read in total (3076ms).
[16:25:25.318] INFO: Test took 4405ms.
[16:25:25.445] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:25.445] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 712182
[16:25:25.445] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:25:25.445] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:25.499] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:26.164] INFO: Expecting 41600 events.
[16:25:29.907] INFO: 41600 events read in total (3217ms).
[16:25:29.912] INFO: Test took 4413ms.
[16:25:30.034] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:30.034] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 724018
[16:25:30.034] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:25:30.035] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:30.093] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:30.759] INFO: Expecting 41600 events.
[16:25:34.539] INFO: 41600 events read in total (3253ms).
[16:25:34.544] INFO: Test took 4451ms.
[16:25:34.664] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:34.664] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 726957
[16:25:34.664] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:25:34.665] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:34.720] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:35.387] INFO: Expecting 41600 events.
[16:25:39.181] INFO: 41600 events read in total (3268ms).
[16:25:39.185] INFO: Test took 4465ms.
[16:25:39.308] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:39.308] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 727402
[16:25:39.308] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:25:39.308] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:39.364] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:40.029] INFO: Expecting 41600 events.
[16:25:43.798] INFO: 41600 events read in total (3242ms).
[16:25:43.802] INFO: Test took 4438ms.
[16:25:43.924] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:43.924] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 726721
[16:25:43.924] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:25:43.925] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:43.981] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:44.646] INFO: Expecting 41600 events.
[16:25:48.457] INFO: 41600 events read in total (3284ms).
[16:25:48.461] INFO: Test took 4480ms.
[16:25:48.643] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:48.643] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 727595
[16:25:48.643] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:25:48.644] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:48.701] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:49.368] INFO: Expecting 41600 events.
[16:25:53.188] INFO: 41600 events read in total (3293ms).
[16:25:53.192] INFO: Test took 4491ms.
[16:25:53.343] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:53.343] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 726776
[16:25:53.343] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:25:53.344] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:53.400] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:54.065] INFO: Expecting 41600 events.
[16:25:57.875] INFO: 41600 events read in total (3283ms).
[16:25:57.880] INFO: Test took 4480ms.
[16:25:58.000] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:58.000] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 725762
[16:25:58.000] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:25:58.000] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:58.056] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:58.721] INFO: Expecting 41600 events.
[16:26:02.539] INFO: 41600 events read in total (3292ms).
[16:26:02.543] INFO: Test took 4487ms.
[16:26:02.665] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:02.666] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 728258
[16:26:02.666] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:26:02.666] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:02.723] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:03.390] INFO: Expecting 41600 events.
[16:26:07.206] INFO: 41600 events read in total (3290ms).
[16:26:07.211] INFO: Test took 4488ms.
[16:26:07.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:07.360] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 726739
[16:26:07.360] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:26:07.361] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:07.418] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:08.085] INFO: Expecting 41600 events.
[16:26:11.897] INFO: 41600 events read in total (3286ms).
[16:26:11.902] INFO: Test took 4484ms.
[16:26:12.024] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:12.024] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 727786
[16:26:12.024] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:26:12.024] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:12.082] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:12.748] INFO: Expecting 41600 events.
[16:26:16.567] INFO: 41600 events read in total (3292ms).
[16:26:16.572] INFO: Test took 4490ms.
[16:26:16.694] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:16.694] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 727725
[16:26:16.694] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:26:16.694] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:16.752] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:17.418] INFO: Expecting 41600 events.
[16:26:21.233] INFO: 41600 events read in total (3288ms).
[16:26:21.238] INFO: Test took 4486ms.
[16:26:21.361] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:21.361] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 727057
[16:26:21.361] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:26:21.361] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:21.418] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:22.083] INFO: Expecting 41600 events.
[16:26:25.895] INFO: 41600 events read in total (3285ms).
[16:26:25.900] INFO: Test took 4482ms.
[16:26:26.023] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:26.023] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 727705
[16:26:26.023] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:26:26.024] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:26.082] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:26.748] INFO: Expecting 41600 events.
[16:26:30.562] INFO: 41600 events read in total (3287ms).
[16:26:30.566] INFO: Test took 4484ms.
[16:26:30.716] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:30.717] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 726596
[16:26:30.717] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:26:30.717] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:30.774] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:31.441] INFO: Expecting 41600 events.
[16:26:35.257] INFO: 41600 events read in total (3290ms).
[16:26:35.261] INFO: Test took 4487ms.
[16:26:35.385] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:35.385] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 729353
[16:26:35.385] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:26:35.385] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:35.443] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:36.109] INFO: Expecting 41600 events.
[16:26:39.922] INFO: 41600 events read in total (3286ms).
[16:26:39.926] INFO: Test took 4483ms.
[16:26:40.047] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:40.047] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 726931
[16:26:40.047] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:26:40.048] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:40.104] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:40.769] INFO: Expecting 41600 events.
[16:26:44.586] INFO: 41600 events read in total (3290ms).
[16:26:44.590] INFO: Test took 4486ms.
[16:26:44.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:44.714] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 727926
[16:26:44.714] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:26:44.714] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:44.771] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:45.436] INFO: Expecting 41600 events.
[16:26:49.225] INFO: 41600 events read in total (3262ms).
[16:26:49.230] INFO: Test took 4459ms.
[16:26:49.353] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:49.353] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 725335
[16:26:49.353] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:26:49.354] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:49.412] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:50.078] INFO: Expecting 41600 events.
[16:26:53.733] INFO: 41600 events read in total (3128ms).
[16:26:53.738] INFO: Test took 4326ms.
[16:26:53.857] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:53.858] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 720124
[16:26:53.858] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:26:53.858] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:54.138] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 149 eff = 0.999279
[16:26:54.138] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 136 eff = 0.999375
[16:26:54.138] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 158 eff = 0.998654
[16:26:54.138] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 145 eff = 0.997813
[16:26:54.138] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 150 eff = 0.997043
[16:26:54.139] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 151 eff = 0.997476
[16:26:54.139] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 159 eff = 0.997404
[16:26:54.139] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 148 eff = 0.997163
[16:26:54.139] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 191 eff = 0.99726
[16:26:54.140] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 153 eff = 0.996587
[16:26:54.140] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 138 eff = 0.996923
[16:26:54.140] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 155 eff = 0.997091
[16:26:54.140] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 153 eff = 0.997356
[16:26:54.141] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 150 eff = 0.99863
[16:26:54.141] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 162 eff = 0.999375
[16:26:54.141] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 144 eff = 0.999279
[16:27:09.201] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:27:09.201] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:27:09.201] INFO: ----------------------------------------------------------------------
[16:27:09.201] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:27:09.201] INFO: ----------------------------------------------------------------------
[16:27:09.202] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:27:09.202] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:27:09.202] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:27:09.202] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:27:09.202] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:27:09.202] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:27:09.202] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:27:09.202] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:27:09.202] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:27:09.202] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:27:09.202] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:27:09.202] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:27:09.221] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:27:09.890] INFO: Expecting 208000 events.
[16:27:25.433] INFO: 208000 events read in total (15016ms).
[16:27:25.451] INFO: Test took 16230ms.
[16:27:26.076] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:26.076] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3339320
[16:27:26.076] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:27:26.077] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:27:26.533] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:27:26.533] INFO: number of red-efficiency pixels: 224 181 262 480 553 518 483 498 533 710 584 565 524 297 203 194
[16:27:26.533] INFO: number of X-ray hits detected: 128003 92767 155250 235462 252251 245238 248709 238948 264743 275906 266138 243214 244153 161343 99413 121222
[16:27:26.533] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:27:26.533] INFO: number of Vcal hits detected: 207769 207788 207712 207474 207393 207427 207475 207453 207431 207205 207357 207355 207429 207672 207786 207802
[16:27:26.533] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.8 99.8 99.7 99.6 99.7 99.7 99.8 99.9 99.9 99.9
[16:27:26.533] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.7 99.7 99.7 99.7 99.7 99.7 99.6 99.7 99.7 99.7 99.8 99.9 99.9
[16:27:26.533] INFO: X-ray hit rate [MHz/cm2]: 37.5 27.2 45.5 69.0 73.9 71.9 72.9 70.0 77.6 80.9 78.0 71.3 71.6 47.3 29.1 35.5
[16:27:26.533] INFO: PixTestHighRate::doXPixelAlive() done
[16:27:26.577] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:27:26.577] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:27:26.577] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:27:26.577] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:27:26.577] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:27:26.577] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:27:26.577] INFO: PixTest:: pg_setup set to default.
[16:27:27.777] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:27:27.777] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[16:27:29.345] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:27:29.346] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 383.5mA
[16:27:29.347] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[16:27:29.347] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[16:27:29.347] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:27:29.347] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:27:29.347] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:27:29.347] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:27:29.347] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:27:29.347] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:27:29.347] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:27:29.347] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:27:29.347] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:27:29.347] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:27:29.442] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:27:29.442] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:27:29.451] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:27:29.451] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:27:29.451] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:27:29.452] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:27:29.452] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:27:29.452] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:27:29.454] QUIET: Connection to board 58 closed.
[16:27:29.534] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[16:22:16.711] INFO: *** Welcome to pxar ***
[16:22:16.711] INFO: *** Today: 2016/09/12
[16:22:16.734] INFO: *** Version: v1.9.0-818-g96727
[16:22:16.734] INFO: readRocDacs: data/mq818/dacParameters35_C0.dat .. data/mq818/dacParameters35_C15.dat
[16:22:16.735] INFO: readTbmDacs: data/mq818/tbmParameters_C0a.dat .. data/mq818/tbmParameters_C0b.dat
[16:22:16.736] INFO: readMaskFile: data/mq818/defaultMaskFile.dat
[16:22:16.736] INFO: readTrimFile: data/mq818/trimParameters35_C0.dat .. data/mq818/trimParameters35_C15.dat
[16:22:16.886] INFO: clk: 4
[16:22:16.886] INFO: ctr: 4
[16:22:16.886] INFO: sda: 19
[16:22:16.886] INFO: tin: 9
[16:22:16.886] INFO: level: 15
[16:22:16.886] INFO: triggerdelay: 0
[16:22:16.886] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:22:16.886] INFO: Log level: DEBUG
[16:22:16.897] QUIET: Connection to board DTB_WRPRHI opened.
[16:22:16.901] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:22:16.903] INFO: RPC call hashes of host and DTB match: 398089610
[16:22:18.507] INFO: DUT info:
[16:22:18.507] INFO: The DUT currently contains the following objects:
[16:22:18.507] INFO: 2 TBM Cores tbm08c (2 ON)
[16:22:18.507] INFO: TBM Core alpha (0): 7 registers set
[16:22:18.507] INFO: TBM Core beta (1): 7 registers set
[16:22:18.507] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:22:18.507] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.507] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.507] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.507] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.507] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.507] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.507] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.507] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.507] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.507] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.507] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.507] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.507] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.507] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.507] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.507] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:22:18.508] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:22:18.509] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:22:18.510] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:22:18.511] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:22:18.513] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30330880
[16:22:18.513] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x12c00e0
[16:22:18.513] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1062370
[16:22:18.513] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fbca3f91010
[16:22:18.513] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fbcaa1fc510
[16:22:18.513] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30339072 fPxarMemory = 0x7fbca3f91010
[16:22:18.514] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[16:22:18.516] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[16:22:18.516] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[16:22:19.029] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x16aef00
[16:22:19.091] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:22:19.091] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:22:19.091] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:22:19.097] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:22:19.097] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:22:19.097] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:22:19.108] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:22:19.108] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:22:19.116] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:22:19.116] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:22:19.138] INFO: PixTestCmd::init()
[16:22:19.145] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:22:19.145] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:22:19.145] INFO: readGainPedestalParameters data/mq818/phCalibrationFitErr35_C0.dat .. data/mq818/phCalibrationFitErr35_C15.dat
[16:22:19.396] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:22:19.396] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:22:19.409] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:22:19.417] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:22:19.417] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:22:19.417] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:22:19.439] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:22:19.439] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:22:19.439] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:22:19.439] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:22:19.439] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:22:19.446] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:22:19.446] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:22:19.461] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:22:19.461] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:22:19.461] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:22:19.461] INFO: readReadbackCal: data/mq818/readbackCal_C0.dat .. data/mq818/readbackCal_C15.dat
[16:22:19.474] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:22:19.482] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:22:19.482] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:22:19.482] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:22:19.482] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:22:19.499] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:22:19.499] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:22:24.860] INFO: ######################################################################
[16:22:24.860] INFO: PixTestAlive::doTest()
[16:22:24.860] INFO: ######################################################################
[16:22:24.864] INFO: ----------------------------------------------------------------------
[16:22:24.864] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:22:24.864] INFO: ----------------------------------------------------------------------
[16:22:24.866] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:22:25.246] INFO: Expecting 41600 events.
[16:22:29.630] INFO: 41600 events read in total (3665ms).
[16:22:29.779] INFO: Test took 4913ms.
[16:22:29.791] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:29.791] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:22:29.791] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:22:30.070] INFO: PixTestAlive::aliveTest() done
[16:22:30.070] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:22:30.070] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 44 26 50 65 82 96 68 75 55 88 65 67 68 52 28 34
[16:22:30.096] INFO: ----------------------------------------------------------------------
[16:22:30.096] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:22:30.096] INFO: ----------------------------------------------------------------------
[16:22:30.098] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:22:30.476] INFO: Expecting 41600 events.
[16:22:33.597] INFO: 41600 events read in total (2406ms).
[16:22:33.597] INFO: Test took 3499ms.
[16:22:33.597] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:33.597] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:22:33.597] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:22:33.598] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:22:34.020] INFO: PixTestAlive::maskTest() done
[16:22:34.020] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:22:34.043] INFO: ----------------------------------------------------------------------
[16:22:34.043] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:22:34.043] INFO: ----------------------------------------------------------------------
[16:22:34.045] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:22:34.422] INFO: Expecting 41600 events.
[16:22:38.823] INFO: 41600 events read in total (3686ms).
[16:22:38.825] INFO: Test took 4780ms.
[16:22:38.836] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:38.836] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:22:38.836] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:22:39.205] INFO: PixTestAlive::addressDecodingTest() done
[16:22:39.205] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:22:39.205] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[16:22:44.905] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:22:44.905] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:22:44.905] INFO: ----------------------------------------------------------------------
[16:22:44.905] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:22:44.905] INFO: ----------------------------------------------------------------------
[16:22:45.045] INFO: Expecting 768 events.
[16:22:46.179] INFO: 768 events read in total (419ms).
[16:22:46.179] INFO: Test took 1268ms.
[16:22:46.185] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:22:46.982] INFO: Expecting 41600 events.
[16:22:50.765] INFO: 41600 events read in total (3256ms).
[16:22:50.770] INFO: Test took 4585ms.
[16:22:50.916] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:50.916] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 850725
[16:22:50.916] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:22:50.917] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:22:50.978] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:22:51.644] INFO: Expecting 41600 events.
[16:22:55.532] INFO: 41600 events read in total (3361ms).
[16:22:55.537] INFO: Test took 4559ms.
[16:22:55.676] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:55.676] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 858956
[16:22:55.676] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:22:55.676] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:22:55.741] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:22:56.406] INFO: Expecting 41600 events.
[16:23:00.341] INFO: 41600 events read in total (3408ms).
[16:23:00.346] INFO: Test took 4605ms.
[16:23:00.484] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:00.484] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 861816
[16:23:00.484] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:23:00.485] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:23:00.550] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:23:01.215] INFO: Expecting 41600 events.
[16:23:05.163] INFO: 41600 events read in total (3421ms).
[16:23:05.168] INFO: Test took 4618ms.
[16:23:05.309] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:05.309] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 863581
[16:23:05.309] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:23:05.310] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:23:05.376] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:23:06.040] INFO: Expecting 41600 events.
[16:23:09.996] INFO: 41600 events read in total (3429ms).
[16:23:10.001] INFO: Test took 4625ms.
[16:23:10.142] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:10.142] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864064
[16:23:10.142] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:23:10.142] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:23:10.209] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:23:10.876] INFO: Expecting 41600 events.
[16:23:14.839] INFO: 41600 events read in total (3436ms).
[16:23:14.844] INFO: Test took 4635ms.
[16:23:14.985] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:14.985] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864312
[16:23:14.985] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:23:14.986] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:23:15.053] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:23:15.718] INFO: Expecting 41600 events.
[16:23:19.692] INFO: 41600 events read in total (3448ms).
[16:23:19.698] INFO: Test took 4645ms.
[16:23:19.839] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:19.839] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864707
[16:23:19.839] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:23:19.839] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:23:19.907] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:23:20.574] INFO: Expecting 41600 events.
[16:23:24.559] INFO: 41600 events read in total (3458ms).
[16:23:24.564] INFO: Test took 4657ms.
[16:23:24.709] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:24.709] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 863507
[16:23:24.709] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:23:24.709] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:23:24.776] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:23:25.442] INFO: Expecting 41600 events.
[16:23:29.417] INFO: 41600 events read in total (3448ms).
[16:23:29.423] INFO: Test took 4647ms.
[16:23:29.564] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:29.564] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864952
[16:23:29.564] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:23:29.565] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:23:29.632] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:23:30.298] INFO: Expecting 41600 events.
[16:23:34.282] INFO: 41600 events read in total (3457ms).
[16:23:34.287] INFO: Test took 4655ms.
[16:23:34.429] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:34.429] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864812
[16:23:34.429] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:23:34.429] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:23:34.497] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:23:35.163] INFO: Expecting 41600 events.
[16:23:39.136] INFO: 41600 events read in total (3446ms).
[16:23:39.141] INFO: Test took 4644ms.
[16:23:39.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:39.283] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864133
[16:23:39.283] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:23:39.283] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:23:39.350] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:23:40.017] INFO: Expecting 41600 events.
[16:23:44.001] INFO: 41600 events read in total (3457ms).
[16:23:44.007] INFO: Test took 4657ms.
[16:23:44.149] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:44.149] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864291
[16:23:44.149] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:23:44.149] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:23:44.217] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:23:44.883] INFO: Expecting 41600 events.
[16:23:48.877] INFO: 41600 events read in total (3467ms).
[16:23:48.882] INFO: Test took 4665ms.
[16:23:49.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:49.022] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864550
[16:23:49.041] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:23:49.041] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:23:49.108] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:23:49.773] INFO: Expecting 41600 events.
[16:23:53.758] INFO: 41600 events read in total (3458ms).
[16:23:53.763] INFO: Test took 4655ms.
[16:23:53.904] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:53.904] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864352
[16:23:53.904] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:23:53.904] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:23:53.970] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:23:54.636] INFO: Expecting 41600 events.
[16:23:58.612] INFO: 41600 events read in total (3449ms).
[16:23:58.617] INFO: Test took 4646ms.
[16:23:58.756] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:58.756] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 863113
[16:23:58.756] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:23:58.757] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:23:58.824] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:23:59.490] INFO: Expecting 41600 events.
[16:24:03.476] INFO: 41600 events read in total (3459ms).
[16:24:03.481] INFO: Test took 4657ms.
[16:24:03.622] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:03.622] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 865267
[16:24:03.623] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:24:03.623] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:24:03.690] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:24:04.357] INFO: Expecting 41600 events.
[16:24:08.342] INFO: 41600 events read in total (3459ms).
[16:24:08.347] INFO: Test took 4657ms.
[16:24:08.489] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:08.489] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 863548
[16:24:08.489] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:24:08.490] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:24:08.556] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:24:09.221] INFO: Expecting 41600 events.
[16:24:13.200] INFO: 41600 events read in total (3452ms).
[16:24:13.205] INFO: Test took 4649ms.
[16:24:13.346] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:13.346] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864533
[16:24:13.346] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:24:13.347] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:24:13.413] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:24:14.078] INFO: Expecting 41600 events.
[16:24:18.030] INFO: 41600 events read in total (3425ms).
[16:24:18.035] INFO: Test took 4622ms.
[16:24:18.175] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:18.176] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 861787
[16:24:18.176] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:24:18.176] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:24:18.243] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:24:18.908] INFO: Expecting 41600 events.
[16:24:22.700] INFO: 41600 events read in total (3265ms).
[16:24:22.706] INFO: Test took 4463ms.
[16:24:22.846] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:22.846] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 857739
[16:24:22.846] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:24:22.846] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:24:23.133] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 146 eff = 0.998822
[16:24:23.133] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 154 eff = 0.999038
[16:24:23.133] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 161 eff = 0.998293
[16:24:23.133] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 145 eff = 0.995913
[16:24:23.133] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 150 eff = 0.995841
[16:24:23.134] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 153 eff = 0.995817
[16:24:23.134] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 159 eff = 0.995769
[16:24:23.134] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 148 eff = 0.996034
[16:24:23.134] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 193 eff = 0.995986
[16:24:23.134] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 158 eff = 0.995433
[16:24:23.134] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 141 eff = 0.995673
[16:24:23.134] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 155 eff = 0.996082
[16:24:23.135] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 153 eff = 0.995817
[16:24:23.135] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 149 eff = 0.997957
[16:24:23.135] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 173 eff = 0.999111
[16:24:23.135] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 146 eff = 0.99899
[16:24:23.721] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:24:23.721] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:24:23.721] INFO: ----------------------------------------------------------------------
[16:24:23.721] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:24:23.721] INFO: ----------------------------------------------------------------------
[16:24:23.722] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:24:23.722] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:24:23.722] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:24:23.722] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:24:23.722] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:24:23.722] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:24:23.722] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:24:23.722] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:24:23.722] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:24:23.722] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:24:23.722] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:24:23.722] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:24:23.740] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:24:24.408] INFO: Expecting 208000 events.
[16:24:41.061] INFO: 208000 events read in total (16126ms).
[16:24:41.082] INFO: Test took 17342ms.
[16:24:41.814] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:41.814] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4012280
[16:24:41.814] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:24:41.815] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:24:42.317] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:24:42.317] INFO: number of red-efficiency pixels: 303 175 392 693 808 697 697 709 800 907 912 773 727 404 198 223
[16:24:42.317] INFO: number of X-ray hits detected: 154138 111896 187048 284426 303683 294683 297948 288591 321204 332377 319592 292744 294707 195673 119993 147017
[16:24:42.317] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:24:42.317] INFO: number of Vcal hits detected: 207677 207796 207556 207204 207065 207206 207215 207181 207100 206889 206900 207057 207137 207547 207793 207736
[16:24:42.317] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.6 99.6 99.7 99.7 99.6 99.6 99.5 99.5 99.6 99.6 99.8 99.9 99.9
[16:24:42.317] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.6 99.6 99.6 99.6 99.6 99.6 99.5 99.5 99.5 99.6 99.8 99.9 99.9
[16:24:42.317] INFO: X-ray hit rate [MHz/cm2]: 45.2 32.8 54.8 83.4 89.0 86.4 87.3 84.6 94.1 97.4 93.7 85.8 86.4 57.4 35.2 43.1
[16:24:42.317] INFO: PixTestHighRate::doXPixelAlive() done
[16:24:42.362] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:24:42.362] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:24:42.362] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:24:42.362] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:24:42.362] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:24:42.362] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:24:42.362] INFO: PixTest:: pg_setup set to default.
[16:24:46.545] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:24:46.545] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[16:24:50.025] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:24:50.026] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[16:24:50.027] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[16:24:50.027] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[16:24:50.027] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:24:50.027] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:24:50.027] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:24:50.027] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:24:50.027] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:24:50.027] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:24:50.027] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:24:50.027] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:24:50.027] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:24:50.027] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:24:50.122] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:24:50.122] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:24:50.131] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:24:50.131] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:24:50.131] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:24:50.131] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:24:50.131] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:24:50.131] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:24:50.134] QUIET: Connection to board 58 closed.
[16:24:50.213] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-904-gd94b9f2 on branch master