[14:24:18.315] INFO: *** Welcome to pxar ***
[14:24:18.315] INFO: *** Today: 2016/09/15
[14:24:18.338] INFO: *** Version: v1.9.0-818-g96727
[14:24:18.339] INFO: readRocDacs: data/mq333/dacParameters35_C0.dat .. data/mq333/dacParameters35_C15.dat
[14:24:18.340] INFO: readTbmDacs: data/mq333/tbmParameters_C0a.dat .. data/mq333/tbmParameters_C0b.dat
[14:24:18.340] INFO: readMaskFile: data/mq333/defaultMaskFile.dat
[14:24:18.340] INFO: readTrimFile: data/mq333/trimParameters35_C0.dat .. data/mq333/trimParameters35_C15.dat
[14:24:18.488] INFO: clk: 4
[14:24:18.488] INFO: ctr: 4
[14:24:18.488] INFO: sda: 19
[14:24:18.488] INFO: tin: 9
[14:24:18.488] INFO: level: 15
[14:24:18.488] INFO: triggerdelay: 0
[14:24:18.488] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[14:24:18.488] INFO: Log level: DEBUG
[14:24:18.499] QUIET: Connection to board DTB_WRPRHI opened.
[14:24:18.503] 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:
------------------------------------------------------
[14:24:18.505] INFO: RPC call hashes of host and DTB match: 398089610
[14:24:20.107] INFO: DUT info:
[14:24:20.107] INFO: The DUT currently contains the following objects:
[14:24:20.107] INFO: 2 TBM Cores tbm08c (2 ON)
[14:24:20.107] INFO: TBM Core alpha (0): 7 registers set
[14:24:20.107] INFO: TBM Core beta (1): 7 registers set
[14:24:20.107] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:24:20.108] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:24:20.108] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:24:20.109] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:24:20.110] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:24:20.111] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:24:20.113] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35008512
[14:24:20.113] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x21b00e0
[14:24:20.113] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1f52370
[14:24:20.113] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f44f1d94010
[14:24:20.113] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f44f7fff510
[14:24:20.113] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35016704 fPxarMemory = 0x7f44f1d94010
[14:24:20.115] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 384.3mA
[14:24:20.116] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 472.7mA
[14:24:20.116] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[14:24:20.626] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x259efc0
[14:24:20.694] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:24:20.694] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:24:20.694] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:24:20.700] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:24:20.700] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:24:20.700] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:24:20.715] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:24:20.715] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:24:20.735] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:24:20.735] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:24:20.752] INFO: PixTestCmd::init()
[14:24:20.762] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:24:20.762] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:24:20.762] INFO: readGainPedestalParameters data/mq333/phCalibrationFitErr35_C0.dat .. data/mq333/phCalibrationFitErr35_C15.dat
[14:24:21.010] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:24:21.010] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:24:21.024] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:24:21.031] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:24:21.031] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:24:21.031] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:24:21.053] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:24:21.053] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:24:21.053] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:24:21.053] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:24:21.053] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:24:21.059] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:24:21.059] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:24:21.078] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:24:21.078] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:24:21.078] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:24:21.078] INFO: readReadbackCal: data/mq333/readbackCal_C0.dat .. data/mq333/readbackCal_C15.dat
[14:24:21.088] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:24:21.096] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[14:24:21.096] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[14:24:21.096] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[14:24:21.096] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[14:24:21.113] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:24:21.113] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:24:26.966] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[14:24:27.901] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[14:24:27.901] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[14:24:27.901] INFO: ----------------------------------------------------------------------
[14:24:27.901] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[14:24:27.901] INFO: ----------------------------------------------------------------------
[14:24:28.876] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[14:24:41.081] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[14:24:41.085] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:25:08.546] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1202458 events.
[14:25:14.949] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1202458, pixels seen in all events: 5822093
[14:25:15.206] INFO: Resuming triggers.
[14:25:27.412] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[14:25:27.455] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:25:54.713] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1202492 events.
[14:26:01.079] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1202492, pixels seen in all events: 5820834
[14:26:01.325] INFO: Resuming triggers.
[14:26:13.532] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[14:26:13.573] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:26:40.772] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1202552 events.
[14:26:47.170] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1202552, pixels seen in all events: 5819823
[14:26:47.423] INFO: Resuming triggers.
[14:26:59.636] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[14:26:59.677] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:27:26.889] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1203145 events.
[14:27:33.349] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1203145, pixels seen in all events: 5812865
[14:27:33.528] INFO: Resuming triggers.
[14:27:44.778] INFO: data taking finished, elapsed time: 60 seconds.
[14:27:44.974] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:28:10.163] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1108331 events.
[14:28:16.029] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1108331, pixels seen in all events: 5359755
[14:28:16.257] INFO: PixTest:: pg_setup set to default.
[14:28:16.301] INFO: PixTestXray::doPhRun() done
[14:28:23.109] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[14:28:23.110] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 388.3mA
[14:28:23.111] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 473.5mA
[14:28:23.111] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[14:28:23.111] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[14:28:23.111] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[14:28:23.111] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[14:28:23.111] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[14:28:23.112] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[14:28:23.112] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[14:28:23.112] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[14:28:23.112] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[14:28:23.112] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[14:28:23.112] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:28:23.112] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[14:28:23.112] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:28:23.112] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[14:28:23.112] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[14:28:23.112] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[14:28:23.112] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[14:28:23.112] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[14:28:23.112] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:28:23.391] QUIET: Connection to board 58 closed.
[14:28:23.471] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[14:28:44.732] INFO: *** Welcome to pxar ***
[14:28:44.732] INFO: *** Today: 2016/09/15
[14:28:44.759] INFO: *** Version: v1.9.0-818-g96727
[14:28:44.759] INFO: readRocDacs: data/mq333/dacParameters35_C0.dat .. data/mq333/dacParameters35_C15.dat
[14:28:44.760] INFO: readTbmDacs: data/mq333/tbmParameters_C0a.dat .. data/mq333/tbmParameters_C0b.dat
[14:28:44.760] INFO: readMaskFile: data/mq333/defaultMaskFile.dat
[14:28:44.760] INFO: readTrimFile: data/mq333/trimParameters35_C0.dat .. data/mq333/trimParameters35_C15.dat
[14:28:44.909] INFO: clk: 4
[14:28:44.909] INFO: ctr: 4
[14:28:44.909] INFO: sda: 19
[14:28:44.909] INFO: tin: 9
[14:28:44.909] INFO: level: 15
[14:28:44.909] INFO: triggerdelay: 0
[14:28:44.909] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[14:28:44.909] INFO: Log level: DEBUG
[14:28:44.920] QUIET: Connection to board DTB_WRPRHI opened.
[14:28:44.923] 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:
------------------------------------------------------
[14:28:44.926] INFO: RPC call hashes of host and DTB match: 398089610
[14:28:46.528] INFO: DUT info:
[14:28:46.528] INFO: The DUT currently contains the following objects:
[14:28:46.528] INFO: 2 TBM Cores tbm08c (2 ON)
[14:28:46.528] INFO: TBM Core alpha (0): 7 registers set
[14:28:46.528] INFO: TBM Core beta (1): 7 registers set
[14:28:46.528] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:28:46.528] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.528] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.528] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.528] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.528] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.528] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.528] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.529] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.529] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.529] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.529] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.529] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.529] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.529] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.529] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.529] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:28:46.529] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:28:46.530] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:28:46.531] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:28:46.532] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:28:46.534] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31350784
[14:28:46.534] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xfda0e0
[14:28:46.534] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xd7c370
[14:28:46.534] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fcf2dd94010
[14:28:46.534] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fcf33fff510
[14:28:46.534] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31358976 fPxarMemory = 0x7fcf2dd94010
[14:28:46.536] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 384.3mA
[14:28:46.537] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 472.7mA
[14:28:46.537] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[14:28:47.028] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x13c9020
[14:28:47.089] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:28:47.089] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:28:47.089] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:28:47.095] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:28:47.095] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:28:47.095] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:28:47.105] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:28:47.105] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:28:47.120] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:28:47.120] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:28:47.134] INFO: PixTestCmd::init()
[14:28:47.143] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:28:47.144] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:28:47.144] INFO: readGainPedestalParameters data/mq333/phCalibrationFitErr35_C0.dat .. data/mq333/phCalibrationFitErr35_C15.dat
[14:28:47.395] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:28:47.395] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:28:47.409] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:28:47.415] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:28:47.415] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:28:47.415] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:28:47.438] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:28:47.438] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:28:47.438] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:28:47.438] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:28:47.438] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:28:47.444] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:28:47.445] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:28:47.459] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:28:47.459] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:28:47.459] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:28:47.459] INFO: readReadbackCal: data/mq333/readbackCal_C0.dat .. data/mq333/readbackCal_C15.dat
[14:28:47.470] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:28:47.478] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[14:28:47.478] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[14:28:47.478] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[14:28:47.478] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[14:28:47.498] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:28:47.499] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:28:59.501] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[14:29:00.405] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[14:29:00.406] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[14:29:00.406] INFO: ----------------------------------------------------------------------
[14:29:00.406] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[14:29:00.406] INFO: ----------------------------------------------------------------------
[14:29:01.379] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[14:29:08.855] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[14:29:08.859] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:29:33.933] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 736602 events.
[14:29:46.294] DEBUG: <PixTestXray.cc/processData:L823> # events read: 736602, pixels seen in all events: 11413950
[14:29:46.533] INFO: Resuming triggers.
[14:29:54.002] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[14:29:54.045] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:30:19.040] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 735763 events.
[14:30:31.383] DEBUG: <PixTestXray.cc/processData:L823> # events read: 735763, pixels seen in all events: 11422757
[14:30:31.565] INFO: Resuming triggers.
[14:30:39.037] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[14:30:39.103] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:31:04.163] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 736057 events.
[14:31:16.544] DEBUG: <PixTestXray.cc/processData:L823> # events read: 736057, pixels seen in all events: 11417916
[14:31:16.728] INFO: Resuming triggers.
[14:31:24.202] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[14:31:24.274] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:31:49.318] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 736231 events.
[14:32:01.653] DEBUG: <PixTestXray.cc/processData:L823> # events read: 736231, pixels seen in all events: 11416200
[14:32:01.849] INFO: Resuming triggers.
[14:32:09.324] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[14:32:09.395] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:32:34.430] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 736388 events.
[14:32:46.798] DEBUG: <PixTestXray.cc/processData:L823> # events read: 736388, pixels seen in all events: 11414841
[14:32:46.992] INFO: Resuming triggers.
[14:32:54.464] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[14:32:54.510] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:33:19.665] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 736083 events.
[14:33:32.058] DEBUG: <PixTestXray.cc/processData:L823> # events read: 736083, pixels seen in all events: 11417881
[14:33:32.275] INFO: Resuming triggers.
[14:33:39.748] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[14:33:39.790] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:34:04.871] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 736194 events.
[14:34:17.256] DEBUG: <PixTestXray.cc/processData:L823> # events read: 736194, pixels seen in all events: 11417173
[14:34:17.479] INFO: Resuming triggers.
[14:34:24.952] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[14:34:24.992] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:34:50.075] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 736156 events.
[14:35:02.448] DEBUG: <PixTestXray.cc/processData:L823> # events read: 736156, pixels seen in all events: 11417534
[14:35:02.668] INFO: Resuming triggers.
[14:35:02.962] INFO: data taking finished, elapsed time: 60 seconds.
[14:35:03.158] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:35:04.323] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 28937 events.
[14:35:04.815] DEBUG: <PixTestXray.cc/processData:L823> # events read: 28937, pixels seen in all events: 448629
[14:35:04.862] INFO: PixTest:: pg_setup set to default.
[14:35:04.915] INFO: PixTestXray::doPhRun() done
[14:45:43.414] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[14:45:43.415] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 387.5mA
[14:45:43.415] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 472.7mA
[14:45:43.415] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[14:45:43.415] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[14:45:43.415] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[14:45:43.416] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[14:45:43.416] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[14:45:43.416] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[14:45:43.416] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[14:45:43.416] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[14:45:43.416] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[14:45:43.416] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[14:45:43.416] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:45:43.416] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[14:45:43.416] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:45:43.416] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[14:45:43.416] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[14:45:43.416] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[14:45:43.417] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[14:45:43.417] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[14:45:43.417] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:45:43.731] QUIET: Connection to board 58 closed.
[14:45:43.810] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[14:18:30.235] INFO: *** Welcome to pxar ***
[14:18:30.235] INFO: *** Today: 2016/09/15
[14:18:30.259] INFO: *** Version: v1.9.0-818-g96727
[14:18:30.259] INFO: readRocDacs: data/mq333/dacParameters35_C0.dat .. data/mq333/dacParameters35_C15.dat
[14:18:30.260] INFO: readTbmDacs: data/mq333/tbmParameters_C0a.dat .. data/mq333/tbmParameters_C0b.dat
[14:18:30.260] INFO: readMaskFile: data/mq333/defaultMaskFile.dat
[14:18:30.260] INFO: readTrimFile: data/mq333/trimParameters35_C0.dat .. data/mq333/trimParameters35_C15.dat
[14:18:30.413] INFO: clk: 4
[14:18:30.413] INFO: ctr: 4
[14:18:30.413] INFO: sda: 19
[14:18:30.413] INFO: tin: 9
[14:18:30.413] INFO: level: 15
[14:18:30.413] INFO: triggerdelay: 0
[14:18:30.413] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[14:18:30.413] INFO: Log level: DEBUG
[14:18:30.424] QUIET: Connection to board DTB_WRPRHI opened.
[14:18:30.428] 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:
------------------------------------------------------
[14:18:30.430] INFO: RPC call hashes of host and DTB match: 398089610
[14:18:32.034] INFO: DUT info:
[14:18:32.034] INFO: The DUT currently contains the following objects:
[14:18:32.034] INFO: 2 TBM Cores tbm08c (2 ON)
[14:18:32.034] INFO: TBM Core alpha (0): 7 registers set
[14:18:32.034] INFO: TBM Core beta (1): 7 registers set
[14:18:32.034] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:18:32.034] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.034] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.034] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.034] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.034] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.034] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.034] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.034] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.034] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.034] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.034] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.034] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.034] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.034] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.034] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.035] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:18:32.035] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:18:32.036] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:18:32.037] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:18:32.038] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:18:32.040] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31371264
[14:18:32.040] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2b6a0e0
[14:18:32.040] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x290c370
[14:18:32.040] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fe05dd94010
[14:18:32.040] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fe063fff510
[14:18:32.040] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31379456 fPxarMemory = 0x7fe05dd94010
[14:18:32.042] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 384.3mA
[14:18:32.043] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 472.7mA
[14:18:32.043] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[14:18:32.541] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2f58f00
[14:18:32.610] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:18:32.610] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:18:32.610] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:18:32.616] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:18:32.616] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:18:32.616] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:18:32.628] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:18:32.628] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:18:32.645] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:18:32.645] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:18:32.661] INFO: PixTestCmd::init()
[14:18:32.669] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:18:32.669] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:18:32.669] INFO: readGainPedestalParameters data/mq333/phCalibrationFitErr35_C0.dat .. data/mq333/phCalibrationFitErr35_C15.dat
[14:18:32.917] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:18:32.917] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:18:32.931] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:18:32.937] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:18:32.937] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:18:32.937] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:18:32.963] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:18:32.963] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:18:32.963] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:18:32.963] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:18:32.963] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:18:32.970] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:18:32.970] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:18:32.990] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:18:32.990] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:18:32.991] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:18:32.991] INFO: readReadbackCal: data/mq333/readbackCal_C0.dat .. data/mq333/readbackCal_C15.dat
[14:18:33.000] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:18:33.008] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[14:18:33.008] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[14:18:33.008] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[14:18:33.008] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[14:18:33.027] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:18:33.027] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:18:39.416] INFO: ######################################################################
[14:18:39.417] INFO: PixTestAlive::doTest()
[14:18:39.417] INFO: ######################################################################
[14:18:39.420] INFO: ----------------------------------------------------------------------
[14:18:39.420] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:18:39.420] INFO: ----------------------------------------------------------------------
[14:18:39.422] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:18:39.803] INFO: Expecting 41600 events.
[14:18:44.183] INFO: 41600 events read in total (3662ms).
[14:18:44.329] INFO: Test took 4906ms.
[14:18:44.339] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:18:44.339] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[14:18:44.339] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:18:44.616] INFO: PixTestAlive::aliveTest() done
[14:18:44.616] INFO: number of dead pixels (per ROC): 0 0 1 1 0 0 0 0 0 0 0 1 0 0 0 0
[14:18:44.616] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 22 14 22 59 37 44 50 58 51 47 44 47 43 27 15 25
[14:18:44.642] INFO: ----------------------------------------------------------------------
[14:18:44.642] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:18:44.642] INFO: ----------------------------------------------------------------------
[14:18:44.644] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:18:45.022] INFO: Expecting 41600 events.
[14:18:48.130] INFO: 41600 events read in total (2392ms).
[14:18:48.130] INFO: Test took 3486ms.
[14:18:48.130] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:18:48.130] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[14:18:48.130] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[14:18:48.131] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:18:48.553] INFO: PixTestAlive::maskTest() done
[14:18:48.553] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:18:48.576] INFO: ----------------------------------------------------------------------
[14:18:48.576] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:18:48.576] INFO: ----------------------------------------------------------------------
[14:18:48.578] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:18:48.955] INFO: Expecting 41600 events.
[14:18:53.337] INFO: 41600 events read in total (3666ms).
[14:18:53.338] INFO: Test took 4760ms.
[14:18:53.350] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:18:53.350] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[14:18:53.350] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[14:18:53.720] INFO: PixTestAlive::addressDecodingTest() done
[14:18:53.720] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:18:53.720] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[14:19:00.349] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:19:00.349] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C1_V0 -> PixelAlive_mod
[14:19:04.149] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[14:19:04.149] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:19:04.149] INFO: ----------------------------------------------------------------------
[14:19:04.150] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:19:04.150] INFO: ----------------------------------------------------------------------
[14:19:04.288] INFO: Expecting 768 events.
[14:19:05.422] INFO: 768 events read in total (418ms).
[14:19:05.423] INFO: Test took 1268ms.
[14:19:05.429] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:19:06.226] INFO: Expecting 41600 events.
[14:19:09.538] INFO: 41600 events read in total (2785ms).
[14:19:09.543] INFO: Test took 4114ms.
[14:19:09.631] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:09.631] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 481993
[14:19:09.632] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:19:09.632] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:19:09.672] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:19:10.338] INFO: Expecting 41600 events.
[14:19:13.795] INFO: 41600 events read in total (2931ms).
[14:19:13.799] INFO: Test took 4126ms.
[14:19:13.884] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:13.884] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 490948
[14:19:13.884] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:19:13.884] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:19:13.926] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:19:14.593] INFO: Expecting 41600 events.
[14:19:18.108] INFO: 41600 events read in total (2989ms).
[14:19:18.112] INFO: Test took 4186ms.
[14:19:18.192] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:18.192] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 494922
[14:19:18.192] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:19:18.193] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:19:18.233] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:19:18.898] INFO: Expecting 41600 events.
[14:19:22.421] INFO: 41600 events read in total (2996ms).
[14:19:22.425] INFO: Test took 4192ms.
[14:19:22.507] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:22.507] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495024
[14:19:22.507] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:19:22.507] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:19:22.550] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:19:23.216] INFO: Expecting 41600 events.
[14:19:26.743] INFO: 41600 events read in total (3000ms).
[14:19:26.747] INFO: Test took 4197ms.
[14:19:26.828] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:26.828] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 494529
[14:19:26.828] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:19:26.828] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:19:26.868] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:19:27.533] INFO: Expecting 41600 events.
[14:19:31.067] INFO: 41600 events read in total (3007ms).
[14:19:31.070] INFO: Test took 4202ms.
[14:19:31.151] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:31.152] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 493864
[14:19:31.152] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:19:31.152] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:19:31.193] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:19:31.858] INFO: Expecting 41600 events.
[14:19:35.394] INFO: 41600 events read in total (3010ms).
[14:19:35.398] INFO: Test took 4205ms.
[14:19:35.481] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:35.481] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495701
[14:19:35.481] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:19:35.481] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:19:35.524] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:19:36.191] INFO: Expecting 41600 events.
[14:19:39.738] INFO: 41600 events read in total (3021ms).
[14:19:39.742] INFO: Test took 4218ms.
[14:19:39.825] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:39.825] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495814
[14:19:39.825] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:19:39.825] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:19:39.868] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:19:40.534] INFO: Expecting 41600 events.
[14:19:44.087] INFO: 41600 events read in total (3026ms).
[14:19:44.090] INFO: Test took 4222ms.
[14:19:44.173] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:44.173] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496012
[14:19:44.173] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:19:44.174] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:19:44.216] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:19:44.883] INFO: Expecting 41600 events.
[14:19:48.437] INFO: 41600 events read in total (3027ms).
[14:19:48.441] INFO: Test took 4225ms.
[14:19:48.524] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:48.524] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 494157
[14:19:48.524] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:19:48.524] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:19:48.566] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:19:49.234] INFO: Expecting 41600 events.
[14:19:52.768] INFO: 41600 events read in total (3008ms).
[14:19:52.772] INFO: Test took 4205ms.
[14:19:52.855] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:52.856] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 494293
[14:19:52.856] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:19:52.856] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:19:52.898] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:19:53.565] INFO: Expecting 41600 events.
[14:19:57.104] INFO: 41600 events read in total (3012ms).
[14:19:57.108] INFO: Test took 4210ms.
[14:19:57.191] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:19:57.191] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 494112
[14:19:57.191] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:19:57.192] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:19:57.234] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:19:57.901] INFO: Expecting 41600 events.
[14:20:01.440] INFO: 41600 events read in total (3013ms).
[14:20:01.444] INFO: Test took 4210ms.
[14:20:01.526] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:01.526] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495462
[14:20:01.526] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:20:01.526] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:20:01.567] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:20:02.232] INFO: Expecting 41600 events.
[14:20:05.773] INFO: 41600 events read in total (3014ms).
[14:20:05.777] INFO: Test took 4210ms.
[14:20:05.860] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:05.861] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496804
[14:20:05.861] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:20:05.861] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:20:05.902] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:20:06.567] INFO: Expecting 41600 events.
[14:20:10.118] INFO: 41600 events read in total (3024ms).
[14:20:10.122] INFO: Test took 4220ms.
[14:20:10.202] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:10.202] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495611
[14:20:10.202] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:20:10.203] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:20:10.244] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:20:10.909] INFO: Expecting 41600 events.
[14:20:14.458] INFO: 41600 events read in total (3023ms).
[14:20:14.461] INFO: Test took 4217ms.
[14:20:14.543] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:14.543] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496215
[14:20:14.543] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:20:14.543] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:20:14.584] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:20:15.249] INFO: Expecting 41600 events.
[14:20:18.797] INFO: 41600 events read in total (3021ms).
[14:20:18.801] INFO: Test took 4217ms.
[14:20:18.883] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:18.883] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495430
[14:20:18.883] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:20:18.884] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:20:18.925] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:20:19.590] INFO: Expecting 41600 events.
[14:20:23.142] INFO: 41600 events read in total (3025ms).
[14:20:23.146] INFO: Test took 4221ms.
[14:20:23.229] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:23.229] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495232
[14:20:23.229] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:20:23.229] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:20:23.271] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:20:23.938] INFO: Expecting 41600 events.
[14:20:27.461] INFO: 41600 events read in total (2996ms).
[14:20:27.464] INFO: Test took 4193ms.
[14:20:27.547] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:27.547] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 493141
[14:20:27.547] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:20:27.547] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:20:27.588] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:20:28.258] INFO: Expecting 41600 events.
[14:20:31.661] INFO: 41600 events read in total (2876ms).
[14:20:31.665] INFO: Test took 4077ms.
[14:20:31.747] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:31.747] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 490086
[14:20:31.747] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:20:31.748] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:20:32.011] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 163 eff = 0.999688
[14:20:32.011] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 164 eff = 0.999663
[14:20:32.011] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 150 eff = 0.999303
[14:20:32.011] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 164 eff = 0.998678
[14:20:32.012] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 125 eff = 0.998822
[14:20:32.012] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 170 eff = 0.99899
[14:20:32.012] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 161 eff = 0.998846
[14:20:32.012] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 170 eff = 0.99899
[14:20:32.012] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 166 eff = 0.999038
[14:20:32.012] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 159 eff = 0.998798
[14:20:32.012] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 181 eff = 0.998942
[14:20:32.013] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 170 eff = 0.998462
[14:20:32.013] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 193 eff = 0.998726
[14:20:32.013] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 164 eff = 0.999327
[14:20:32.013] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 178 eff = 0.999736
[14:20:32.013] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 170 eff = 0.999591
[14:20:35.989] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[14:20:35.989] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:20:35.989] INFO: ----------------------------------------------------------------------
[14:20:35.989] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:20:35.990] INFO: ----------------------------------------------------------------------
[14:20:35.990] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:20:35.990] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:20:35.990] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:20:35.990] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:20:35.990] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:20:35.990] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:20:35.990] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:20:35.990] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:20:35.990] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:20:35.990] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:20:35.990] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:20:35.990] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:20:36.008] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:20:36.677] INFO: Expecting 208000 events.
[14:20:50.327] INFO: 208000 events read in total (13124ms).
[14:20:50.339] INFO: Test took 14331ms.
[14:20:50.815] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:50.815] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2193924
[14:20:50.815] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:20:50.815] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:20:51.197] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 1 0 0 0 0
[14:20:51.197] INFO: number of red-efficiency pixels: 118 93 164 240 230 264 275 219 219 220 254 271 279 149 92 111
[14:20:51.197] INFO: number of X-ray hits detected: 88733 61617 104163 157889 163191 159204 168996 155013 157757 172826 167614 159216 161719 105337 64207 79884
[14:20:51.197] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:20:51.197] INFO: number of Vcal hits detected: 207877 207907 207777 207702 207759 207725 207714 207774 207773 207770 207739 207667 207709 207846 207905 207888
[14:20:51.197] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[14:20:51.197] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 100.0 99.9
[14:20:51.197] INFO: X-ray hit rate [MHz/cm2]: 26.0 18.1 30.5 46.3 47.8 46.7 49.5 45.4 46.2 50.7 49.1 46.7 47.4 30.9 18.8 23.4
[14:20:51.197] INFO: PixTestHighRate::doXPixelAlive() done
[14:20:51.242] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:20:51.242] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:20:51.242] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:20:51.242] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:20:51.242] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:20:51.242] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:20:51.242] INFO: PixTest:: pg_setup set to default.
[14:20:56.645] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:20:56.645] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C8_V0 -> highRate_xraymap_mod
[14:20:58.533] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[14:20:58.534] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 388.3mA
[14:20:58.535] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 473.5mA
[14:20:58.535] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[14:20:58.535] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[14:20:58.535] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[14:20:58.535] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[14:20:58.535] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[14:20:58.535] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[14:20:58.535] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[14:20:58.535] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[14:20:58.535] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[14:20:58.535] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[14:20:58.535] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:20:58.629] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[14:20:58.629] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:20:58.643] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[14:20:58.643] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[14:20:58.643] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[14:20:58.643] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[14:20:58.643] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[14:20:58.643] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:20:58.646] QUIET: Connection to board 58 closed.
[14:20:58.726] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[14:15:06.955] INFO: *** Welcome to pxar ***
[14:15:06.955] INFO: *** Today: 2016/09/15
[14:15:06.983] INFO: *** Version: v1.9.0-818-g96727
[14:15:06.983] INFO: readRocDacs: data/mq333/dacParameters35_C0.dat .. data/mq333/dacParameters35_C15.dat
[14:15:06.984] INFO: readTbmDacs: data/mq333/tbmParameters_C0a.dat .. data/mq333/tbmParameters_C0b.dat
[14:15:06.984] INFO: readMaskFile: data/mq333/defaultMaskFile.dat
[14:15:06.984] INFO: readTrimFile: data/mq333/trimParameters35_C0.dat .. data/mq333/trimParameters35_C15.dat
[14:15:07.133] INFO: clk: 4
[14:15:07.133] INFO: ctr: 4
[14:15:07.133] INFO: sda: 19
[14:15:07.133] INFO: tin: 9
[14:15:07.133] INFO: level: 15
[14:15:07.133] INFO: triggerdelay: 0
[14:15:07.133] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[14:15:07.133] INFO: Log level: DEBUG
[14:15:07.144] QUIET: Connection to board DTB_WRPRHI opened.
[14:15:07.147] 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:
------------------------------------------------------
[14:15:07.150] INFO: RPC call hashes of host and DTB match: 398089610
[14:15:08.753] INFO: DUT info:
[14:15:08.753] INFO: The DUT currently contains the following objects:
[14:15:08.753] INFO: 2 TBM Cores tbm08c (2 ON)
[14:15:08.754] INFO: TBM Core alpha (0): 7 registers set
[14:15:08.754] INFO: TBM Core beta (1): 7 registers set
[14:15:08.754] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:15:08.754] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:08.754] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:15:08.754] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:15:08.754] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:15:08.754] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:15:08.755] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:15:08.756] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:15:08.757] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:15:08.758] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:15:08.758] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:15:08.758] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:15:08.760] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33230848
[14:15:08.760] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x189c0e0
[14:15:08.760] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x163e370
[14:15:08.760] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f9d69d94010
[14:15:08.760] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f9d6ffff510
[14:15:08.760] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33239040 fPxarMemory = 0x7f9d69d94010
[14:15:08.761] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 384.3mA
[14:15:08.762] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 472.7mA
[14:15:08.762] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.5 C
[14:15:09.260] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1c8afd0
[14:15:09.323] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:15:09.323] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:15:09.324] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:15:09.329] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:15:09.329] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:15:09.329] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:15:09.341] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:15:09.341] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:15:09.363] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:15:09.363] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:15:09.382] INFO: PixTestCmd::init()
[14:15:09.402] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:15:09.402] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:15:09.402] INFO: readGainPedestalParameters data/mq333/phCalibrationFitErr35_C0.dat .. data/mq333/phCalibrationFitErr35_C15.dat
[14:15:09.650] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:15:09.650] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:15:09.665] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:15:09.672] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:15:09.672] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:15:09.672] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:15:09.694] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:15:09.694] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:15:09.694] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:15:09.694] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:15:09.694] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:15:09.700] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:15:09.700] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:15:09.721] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:15:09.721] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:15:09.721] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:15:09.721] INFO: readReadbackCal: data/mq333/readbackCal_C0.dat .. data/mq333/readbackCal_C15.dat
[14:15:09.730] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:15:09.738] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[14:15:09.738] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[14:15:09.738] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[14:15:09.738] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[14:15:09.758] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:15:09.758] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:15:17.608] INFO: ######################################################################
[14:15:17.608] INFO: PixTestAlive::doTest()
[14:15:17.608] INFO: ######################################################################
[14:15:17.612] INFO: ----------------------------------------------------------------------
[14:15:17.612] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:15:17.612] INFO: ----------------------------------------------------------------------
[14:15:17.614] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:15:17.994] INFO: Expecting 41600 events.
[14:15:22.386] INFO: 41600 events read in total (3674ms).
[14:15:22.531] INFO: Test took 4917ms.
[14:15:22.543] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:22.543] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[14:15:22.543] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:15:22.820] INFO: PixTestAlive::aliveTest() done
[14:15:22.820] INFO: number of dead pixels (per ROC): 0 0 1 1 0 0 0 0 0 0 0 1 0 0 0 0
[14:15:22.820] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 29 31 41 69 57 45 77 60 56 65 67 60 67 48 22 42
[14:15:22.848] INFO: ----------------------------------------------------------------------
[14:15:22.848] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:15:22.848] INFO: ----------------------------------------------------------------------
[14:15:22.851] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:15:23.227] INFO: Expecting 41600 events.
[14:15:26.354] INFO: 41600 events read in total (2411ms).
[14:15:26.354] INFO: Test took 3503ms.
[14:15:26.355] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:26.355] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[14:15:26.355] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[14:15:26.355] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:15:26.777] INFO: PixTestAlive::maskTest() done
[14:15:26.777] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:15:26.799] INFO: ----------------------------------------------------------------------
[14:15:26.799] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:15:26.799] INFO: ----------------------------------------------------------------------
[14:15:26.801] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:15:27.177] INFO: Expecting 41600 events.
[14:15:31.550] INFO: 41600 events read in total (3657ms).
[14:15:31.551] INFO: Test took 4750ms.
[14:15:31.563] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:31.563] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[14:15:31.563] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[14:15:31.933] INFO: PixTestAlive::addressDecodingTest() done
[14:15:31.933] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:15:31.934] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[14:15:34.901] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:15:34.901] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[14:15:37.637] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[14:15:37.637] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:15:37.637] INFO: ----------------------------------------------------------------------
[14:15:37.637] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:15:37.637] INFO: ----------------------------------------------------------------------
[14:15:37.777] INFO: Expecting 768 events.
[14:15:38.911] INFO: 768 events read in total (419ms).
[14:15:38.911] INFO: Test took 1268ms.
[14:15:38.918] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:15:39.715] INFO: Expecting 41600 events.
[14:15:43.362] INFO: 41600 events read in total (3120ms).
[14:15:43.367] INFO: Test took 4449ms.
[14:15:43.553] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:43.553] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 709482
[14:15:43.553] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:15:43.553] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:15:43.607] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:15:44.272] INFO: Expecting 41600 events.
[14:15:48.026] INFO: 41600 events read in total (3228ms).
[14:15:48.030] INFO: Test took 4423ms.
[14:15:48.177] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:48.177] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 715340
[14:15:48.177] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:15:48.178] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:15:48.234] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:15:48.900] INFO: Expecting 41600 events.
[14:15:52.699] INFO: 41600 events read in total (3272ms).
[14:15:52.704] INFO: Test took 4470ms.
[14:15:52.827] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:52.827] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719724
[14:15:52.827] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:15:52.827] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:15:52.885] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:15:53.552] INFO: Expecting 41600 events.
[14:15:57.360] INFO: 41600 events read in total (3282ms).
[14:15:57.365] INFO: Test took 4480ms.
[14:15:57.488] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:57.488] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719859
[14:15:57.488] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:15:57.488] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:15:57.545] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:15:58.212] INFO: Expecting 41600 events.
[14:16:02.019] INFO: 41600 events read in total (3280ms).
[14:16:02.023] INFO: Test took 4478ms.
[14:16:02.144] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:02.144] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719567
[14:16:02.144] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:16:02.144] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:16:02.199] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:16:02.865] INFO: Expecting 41600 events.
[14:16:06.681] INFO: 41600 events read in total (3290ms).
[14:16:06.686] INFO: Test took 4487ms.
[14:16:06.806] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:06.806] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718434
[14:16:06.806] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:16:06.806] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:16:06.861] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:16:07.526] INFO: Expecting 41600 events.
[14:16:11.350] INFO: 41600 events read in total (3297ms).
[14:16:11.355] INFO: Test took 4494ms.
[14:16:11.478] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:11.478] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718458
[14:16:11.478] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:16:11.478] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:16:11.535] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:16:12.202] INFO: Expecting 41600 events.
[14:16:16.029] INFO: 41600 events read in total (3300ms).
[14:16:16.034] INFO: Test took 4499ms.
[14:16:16.155] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:16.155] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718729
[14:16:16.155] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:16:16.156] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:16:16.213] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:16:16.880] INFO: Expecting 41600 events.
[14:16:20.717] INFO: 41600 events read in total (3311ms).
[14:16:20.722] INFO: Test took 4509ms.
[14:16:20.844] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:20.844] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718563
[14:16:20.844] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:16:20.845] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:16:20.902] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:16:21.569] INFO: Expecting 41600 events.
[14:16:25.404] INFO: 41600 events read in total (3308ms).
[14:16:25.409] INFO: Test took 4507ms.
[14:16:25.528] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:25.528] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719048
[14:16:25.528] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:16:25.529] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:16:25.584] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:16:26.250] INFO: Expecting 41600 events.
[14:16:30.090] INFO: 41600 events read in total (3314ms).
[14:16:30.094] INFO: Test took 4510ms.
[14:16:30.218] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:30.218] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719400
[14:16:30.218] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:16:30.218] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:16:30.275] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:16:30.942] INFO: Expecting 41600 events.
[14:16:34.785] INFO: 41600 events read in total (3316ms).
[14:16:34.789] INFO: Test took 4514ms.
[14:16:34.910] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:34.910] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719116
[14:16:34.910] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:16:34.911] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:16:34.967] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:16:35.631] INFO: Expecting 41600 events.
[14:16:39.472] INFO: 41600 events read in total (3314ms).
[14:16:39.476] INFO: Test took 4509ms.
[14:16:39.596] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:39.596] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 720035
[14:16:39.596] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:16:39.596] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:16:39.652] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:16:40.317] INFO: Expecting 41600 events.
[14:16:44.128] INFO: 41600 events read in total (3284ms).
[14:16:44.132] INFO: Test took 4480ms.
[14:16:44.253] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:44.254] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 720518
[14:16:44.254] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:16:44.254] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:16:44.309] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:16:44.975] INFO: Expecting 41600 events.
[14:16:48.821] INFO: 41600 events read in total (3319ms).
[14:16:48.826] INFO: Test took 4517ms.
[14:16:48.948] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:48.948] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718301
[14:16:48.948] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:16:48.948] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:16:49.004] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:16:49.669] INFO: Expecting 41600 events.
[14:16:53.517] INFO: 41600 events read in total (3322ms).
[14:16:53.521] INFO: Test took 4517ms.
[14:16:53.643] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:53.643] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 720301
[14:16:53.643] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:16:53.644] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:16:53.700] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:16:54.365] INFO: Expecting 41600 events.
[14:16:58.192] INFO: 41600 events read in total (3301ms).
[14:16:58.197] INFO: Test took 4497ms.
[14:16:58.317] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:58.317] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 717954
[14:16:58.317] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:16:58.318] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:16:58.373] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:16:59.039] INFO: Expecting 41600 events.
[14:17:02.872] INFO: 41600 events read in total (3307ms).
[14:17:02.876] INFO: Test took 4503ms.
[14:17:02.997] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:02.997] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718717
[14:17:02.997] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:17:02.998] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:17:03.054] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:17:03.719] INFO: Expecting 41600 events.
[14:17:07.511] INFO: 41600 events read in total (3265ms).
[14:17:07.516] INFO: Test took 4462ms.
[14:17:07.635] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:07.636] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718728
[14:17:07.636] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:17:07.636] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:17:07.692] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:17:08.357] INFO: Expecting 41600 events.
[14:17:12.001] INFO: 41600 events read in total (3117ms).
[14:17:12.005] INFO: Test took 4313ms.
[14:17:12.124] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:12.124] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 713417
[14:17:12.124] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:17:12.124] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:17:12.402] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 172 eff = 0.999183
[14:17:12.402] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 154 eff = 0.999351
[14:17:12.402] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 155 eff = 0.99851
[14:17:12.402] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 167 eff = 0.997548
[14:17:12.403] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 124 eff = 0.997644
[14:17:12.403] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 174 eff = 0.998173
[14:17:12.403] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 161 eff = 0.997356
[14:17:12.403] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 164 eff = 0.997308
[14:17:12.403] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 172 eff = 0.998029
[14:17:12.403] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 160 eff = 0.997284
[14:17:12.403] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 181 eff = 0.997668
[14:17:12.404] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 167 eff = 0.99726
[14:17:12.404] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 196 eff = 0.997548
[14:17:12.404] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 166 eff = 0.99863
[14:17:12.404] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 162 eff = 0.999471
[14:17:12.404] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 164 eff = 0.999183
[14:17:16.893] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[14:17:16.893] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:17:16.893] INFO: ----------------------------------------------------------------------
[14:17:16.893] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:17:16.893] INFO: ----------------------------------------------------------------------
[14:17:16.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:17:16.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:17:16.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:17:16.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:17:16.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:17:16.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:17:16.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:17:16.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:17:16.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:17:16.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:17:16.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:17:16.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:17:16.912] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:17:17.585] INFO: Expecting 208000 events.
[14:17:33.032] INFO: 208000 events read in total (14920ms).
[14:17:33.049] INFO: Test took 16137ms.
[14:17:33.829] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:33.829] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3299092
[14:17:33.829] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:17:33.829] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:17:34.280] INFO: number of dead pixels (per ROC): 0 0 1 1 0 0 0 0 0 0 0 1 0 0 0 0
[14:17:34.280] INFO: number of red-efficiency pixels: 192 154 298 513 478 450 504 539 414 480 485 469 475 282 157 208
[14:17:34.280] INFO: number of X-ray hits detected: 135147 93153 158721 239558 248135 242378 255635 234986 239752 262228 255690 241654 245485 160843 98368 120802
[14:17:34.280] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:17:34.280] INFO: number of Vcal hits detected: 207803 207836 207616 207364 207484 207525 207443 207404 207560 207481 207479 207436 207484 207672 207838 207783
[14:17:34.280] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.7 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[14:17:34.280] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.8 99.8 99.7 99.7 99.8 99.8 99.7 99.7 99.8 99.8 99.9 99.9
[14:17:34.280] INFO: X-ray hit rate [MHz/cm2]: 39.6 27.3 46.5 70.2 72.7 71.0 74.9 68.9 70.3 76.9 74.9 70.8 72.0 47.1 28.8 35.4
[14:17:34.280] INFO: PixTestHighRate::doXPixelAlive() done
[14:17:34.326] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:17:34.326] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:17:34.326] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:17:34.326] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:17:34.326] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:17:34.326] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:17:34.326] INFO: PixTest:: pg_setup set to default.
[14:17:39.453] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:17:39.453] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[14:17:41.029] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[14:17:41.030] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 388.3mA
[14:17:41.031] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 473.5mA
[14:17:41.031] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[14:17:41.031] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[14:17:41.031] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[14:17:41.031] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[14:17:41.031] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[14:17:41.031] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[14:17:41.031] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[14:17:41.031] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[14:17:41.031] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[14:17:41.031] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[14:17:41.032] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:17:41.125] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[14:17:41.126] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:17:41.139] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[14:17:41.139] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[14:17:41.139] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[14:17:41.139] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[14:17:41.139] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[14:17:41.139] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:17:41.142] QUIET: Connection to board 58 closed.
[14:17:41.221] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[14:11:25.421] INFO: *** Welcome to pxar ***
[14:11:25.421] INFO: *** Today: 2016/09/15
[14:11:25.449] INFO: *** Version: v1.9.0-818-g96727
[14:11:25.450] INFO: readRocDacs: data/mq333/dacParameters35_C0.dat .. data/mq333/dacParameters35_C15.dat
[14:11:25.451] INFO: readTbmDacs: data/mq333/tbmParameters_C0a.dat .. data/mq333/tbmParameters_C0b.dat
[14:11:25.451] INFO: readMaskFile: data/mq333/defaultMaskFile.dat
[14:11:25.451] INFO: readTrimFile: data/mq333/trimParameters35_C0.dat .. data/mq333/trimParameters35_C15.dat
[14:11:25.599] INFO: clk: 4
[14:11:25.599] INFO: ctr: 4
[14:11:25.599] INFO: sda: 19
[14:11:25.599] INFO: tin: 9
[14:11:25.599] INFO: level: 15
[14:11:25.599] INFO: triggerdelay: 0
[14:11:25.599] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[14:11:25.599] INFO: Log level: DEBUG
[14:11:25.611] QUIET: Connection to board DTB_WRPRHI opened.
[14:11:25.614] 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:
------------------------------------------------------
[14:11:25.617] INFO: RPC call hashes of host and DTB match: 398089610
[14:11:27.221] INFO: DUT info:
[14:11:27.221] INFO: The DUT currently contains the following objects:
[14:11:27.221] INFO: 2 TBM Cores tbm08c (2 ON)
[14:11:27.221] INFO: TBM Core alpha (0): 7 registers set
[14:11:27.221] INFO: TBM Core beta (1): 7 registers set
[14:11:27.221] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:11:27.221] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.221] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.221] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.221] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.221] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.221] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.221] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.222] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.222] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.222] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.222] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.222] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.222] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.222] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.222] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.222] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:11:27.222] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:11:27.223] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:11:27.224] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:11:27.225] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:11:27.227] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34811904
[14:11:27.227] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2a630e0
[14:11:27.227] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2805370
[14:11:27.227] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f0a75d94010
[14:11:27.227] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f0a7bfff510
[14:11:27.227] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34820096 fPxarMemory = 0x7f0a75d94010
[14:11:27.229] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 384.3mA
[14:11:27.230] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 472.7mA
[14:11:27.230] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[14:11:27.727] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2e51f60
[14:11:27.788] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:11:27.789] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:11:27.789] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:11:27.794] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:11:27.794] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:11:27.794] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:11:27.804] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:11:27.804] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:11:27.819] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:11:27.820] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:11:27.844] INFO: PixTestCmd::init()
[14:11:27.860] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:11:27.860] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:11:27.860] INFO: readGainPedestalParameters data/mq333/phCalibrationFitErr35_C0.dat .. data/mq333/phCalibrationFitErr35_C15.dat
[14:11:28.108] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:11:28.108] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:11:28.122] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:11:28.128] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:11:28.128] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:11:28.128] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:11:28.152] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:11:28.152] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:11:28.152] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:11:28.152] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:11:28.152] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:11:28.158] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:11:28.158] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:11:28.181] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:11:28.181] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:11:28.181] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:11:28.181] INFO: readReadbackCal: data/mq333/readbackCal_C0.dat .. data/mq333/readbackCal_C15.dat
[14:11:28.190] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:11:28.200] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[14:11:28.200] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[14:11:28.200] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[14:11:28.200] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[14:11:28.218] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:11:28.218] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:11:40.048] INFO: ######################################################################
[14:11:40.048] INFO: PixTestAlive::doTest()
[14:11:40.048] INFO: ######################################################################
[14:11:40.052] INFO: ----------------------------------------------------------------------
[14:11:40.052] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:11:40.052] INFO: ----------------------------------------------------------------------
[14:11:40.054] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:11:40.434] INFO: Expecting 41600 events.
[14:11:44.798] INFO: 41600 events read in total (3646ms).
[14:11:44.944] INFO: Test took 4890ms.
[14:11:44.956] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:44.956] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[14:11:44.956] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:11:45.236] INFO: PixTestAlive::aliveTest() done
[14:11:45.236] INFO: number of dead pixels (per ROC): 0 0 1 1 0 0 0 0 0 0 0 1 0 0 0 0
[14:11:45.236] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 59 29 63 85 85 79 80 92 79 101 93 85 83 52 30 34
[14:11:45.263] INFO: ----------------------------------------------------------------------
[14:11:45.263] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:11:45.263] INFO: ----------------------------------------------------------------------
[14:11:45.265] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:11:45.643] INFO: Expecting 41600 events.
[14:11:48.770] INFO: 41600 events read in total (2412ms).
[14:11:48.770] INFO: Test took 3505ms.
[14:11:48.770] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:48.770] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[14:11:48.770] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[14:11:48.771] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:11:49.192] INFO: PixTestAlive::maskTest() done
[14:11:49.192] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:11:49.215] INFO: ----------------------------------------------------------------------
[14:11:49.215] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:11:49.215] INFO: ----------------------------------------------------------------------
[14:11:49.217] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:11:49.595] INFO: Expecting 41600 events.
[14:11:53.983] INFO: 41600 events read in total (3673ms).
[14:11:53.984] INFO: Test took 4767ms.
[14:11:53.996] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:53.996] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[14:11:53.996] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[14:11:54.365] INFO: PixTestAlive::addressDecodingTest() done
[14:11:54.365] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:11:54.365] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[14:12:09.029] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:12:09.029] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[14:12:11.605] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[14:12:11.605] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:12:11.605] INFO: ----------------------------------------------------------------------
[14:12:11.605] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:12:11.605] INFO: ----------------------------------------------------------------------
[14:12:11.744] INFO: Expecting 768 events.
[14:12:12.877] INFO: 768 events read in total (417ms).
[14:12:12.877] INFO: Test took 1266ms.
[14:12:12.883] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:12:13.681] INFO: Expecting 41600 events.
[14:12:17.599] INFO: 41600 events read in total (3392ms).
[14:12:17.604] INFO: Test took 4721ms.
[14:12:17.766] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:17.766] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 929169
[14:12:17.766] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:12:17.766] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:12:17.834] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:12:18.500] INFO: Expecting 41600 events.
[14:12:22.503] INFO: 41600 events read in total (3476ms).
[14:12:22.508] INFO: Test took 4674ms.
[14:12:22.659] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:22.659] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 936922
[14:12:22.659] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:12:22.660] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:12:22.731] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:12:23.396] INFO: Expecting 41600 events.
[14:12:27.432] INFO: 41600 events read in total (3509ms).
[14:12:27.437] INFO: Test took 4706ms.
[14:12:27.587] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:27.587] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 937565
[14:12:27.587] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:12:27.587] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:12:27.658] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:12:28.325] INFO: Expecting 41600 events.
[14:12:32.368] INFO: 41600 events read in total (3517ms).
[14:12:32.374] INFO: Test took 4716ms.
[14:12:32.527] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:32.527] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 940097
[14:12:32.527] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:12:32.527] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:12:32.598] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:12:33.264] INFO: Expecting 41600 events.
[14:12:37.317] INFO: 41600 events read in total (3526ms).
[14:12:37.323] INFO: Test took 4725ms.
[14:12:37.478] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:37.478] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 940340
[14:12:37.478] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:12:37.479] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:12:37.546] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:12:38.211] INFO: Expecting 41600 events.
[14:12:42.267] INFO: 41600 events read in total (3529ms).
[14:12:42.273] INFO: Test took 4727ms.
[14:12:42.424] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:42.424] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 940926
[14:12:42.424] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:12:42.425] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:12:42.496] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:12:43.161] INFO: Expecting 41600 events.
[14:12:47.246] INFO: 41600 events read in total (3558ms).
[14:12:47.252] INFO: Test took 4756ms.
[14:12:47.408] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:47.408] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 941049
[14:12:47.408] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:12:47.408] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:12:47.477] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:12:48.142] INFO: Expecting 41600 events.
[14:12:52.214] INFO: 41600 events read in total (3545ms).
[14:12:52.219] INFO: Test took 4742ms.
[14:12:52.374] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:52.374] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 941010
[14:12:52.374] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:12:52.374] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:12:52.444] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:12:53.108] INFO: Expecting 41600 events.
[14:12:57.185] INFO: 41600 events read in total (3550ms).
[14:12:57.191] INFO: Test took 4747ms.
[14:12:57.402] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:57.402] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 940507
[14:12:57.402] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:12:57.402] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:12:57.470] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:12:58.135] INFO: Expecting 41600 events.
[14:13:02.205] INFO: 41600 events read in total (3543ms).
[14:13:02.210] INFO: Test took 4740ms.
[14:13:02.370] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:02.370] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 940496
[14:13:02.370] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:13:02.370] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:13:02.437] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:13:03.102] INFO: Expecting 41600 events.
[14:13:07.171] INFO: 41600 events read in total (3542ms).
[14:13:07.176] INFO: Test took 4739ms.
[14:13:07.333] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:07.333] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 938672
[14:13:07.333] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:13:07.334] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:13:07.404] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:13:08.069] INFO: Expecting 41600 events.
[14:13:12.142] INFO: 41600 events read in total (3547ms).
[14:13:12.148] INFO: Test took 4744ms.
[14:13:12.301] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:12.301] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 939906
[14:13:12.301] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:13:12.301] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:13:12.372] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:13:13.037] INFO: Expecting 41600 events.
[14:13:17.085] INFO: 41600 events read in total (3521ms).
[14:13:17.091] INFO: Test took 4719ms.
[14:13:17.247] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:17.247] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 939123
[14:13:17.247] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:13:17.247] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:13:17.316] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:13:17.983] INFO: Expecting 41600 events.
[14:13:22.044] INFO: 41600 events read in total (3534ms).
[14:13:22.050] INFO: Test took 4734ms.
[14:13:22.206] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:22.206] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 940657
[14:13:22.206] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:13:22.206] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:13:22.275] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:13:22.940] INFO: Expecting 41600 events.
[14:13:27.007] INFO: 41600 events read in total (3540ms).
[14:13:27.013] INFO: Test took 4738ms.
[14:13:27.168] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:27.168] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 939845
[14:13:27.168] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:13:27.169] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:13:27.237] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:13:27.902] INFO: Expecting 41600 events.
[14:13:31.972] INFO: 41600 events read in total (3544ms).
[14:13:31.977] INFO: Test took 4740ms.
[14:13:32.142] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:32.142] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 941013
[14:13:32.142] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:13:32.143] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:13:32.215] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:13:32.880] INFO: Expecting 41600 events.
[14:13:36.964] INFO: 41600 events read in total (3558ms).
[14:13:36.969] INFO: Test took 4754ms.
[14:13:37.124] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:37.124] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 937900
[14:13:37.124] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:13:37.125] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:13:37.193] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:13:37.858] INFO: Expecting 41600 events.
[14:13:41.925] INFO: 41600 events read in total (3541ms).
[14:13:41.930] INFO: Test took 4737ms.
[14:13:42.086] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:42.086] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 940213
[14:13:42.086] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:13:42.086] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:13:42.155] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:13:42.820] INFO: Expecting 41600 events.
[14:13:46.875] INFO: 41600 events read in total (3529ms).
[14:13:46.880] INFO: Test took 4725ms.
[14:13:47.031] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:47.031] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 938859
[14:13:47.031] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:13:47.032] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:13:47.103] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:13:47.768] INFO: Expecting 41600 events.
[14:13:51.665] INFO: 41600 events read in total (3371ms).
[14:13:51.671] INFO: Test took 4568ms.
[14:13:51.822] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:51.822] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 933166
[14:13:51.822] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:13:51.822] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:13:52.114] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 170 eff = 0.99863
[14:13:52.114] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 172 eff = 0.999183
[14:13:52.115] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 156 eff = 0.9975
[14:13:52.115] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 167 eff = 0.995577
[14:13:52.115] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 125 eff = 0.995072
[14:13:52.115] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 173 eff = 0.995865
[14:13:52.116] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 164 eff = 0.994976
[14:13:52.116] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 164 eff = 0.995313
[14:13:52.116] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 172 eff = 0.995577
[14:13:52.116] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 157 eff = 0.994375
[14:13:52.117] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 181 eff = 0.994663
[14:13:52.117] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 170 eff = 0.99476
[14:13:52.117] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 196 eff = 0.994688
[14:13:52.117] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 164 eff = 0.99726
[14:13:52.118] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 178 eff = 0.998966
[14:13:52.118] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 167 eff = 0.99875
[14:13:58.141] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[14:13:58.141] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:13:58.141] INFO: ----------------------------------------------------------------------
[14:13:58.141] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:13:58.141] INFO: ----------------------------------------------------------------------
[14:13:58.142] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:13:58.142] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:13:58.142] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:13:58.142] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:13:58.142] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:13:58.142] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:13:58.142] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:13:58.142] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:13:58.142] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:13:58.142] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:13:58.142] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:13:58.142] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:13:58.160] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:13:58.830] INFO: Expecting 208000 events.
[14:14:16.276] INFO: 208000 events read in total (16919ms).
[14:14:16.299] INFO: Test took 18139ms.
[14:14:17.218] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:14:17.218] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4385240
[14:14:17.218] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:14:17.219] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:14:17.745] INFO: number of dead pixels (per ROC): 0 0 1 1 0 0 0 0 0 0 0 0 0 0 0 0
[14:14:17.745] INFO: number of red-efficiency pixels: 260 206 470 861 824 792 903 869 791 959 924 892 875 484 205 250
[14:14:17.745] INFO: number of X-ray hits detected: 180021 124774 212934 320543 330511 322994 342689 313944 320618 349607 341851 323840 326627 213974 131004 162751
[14:14:17.745] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:14:17.745] INFO: number of Vcal hits detected: 207727 207784 207427 206582 207063 207079 206954 206985 207118 206858 206893 206871 207001 207445 207782 207734
[14:14:17.745] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.5 99.6 99.6 99.5 99.5 99.6 99.5 99.5 99.5 99.6 99.8 99.9 99.9
[14:14:17.745] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.3 99.5 99.6 99.5 99.5 99.6 99.5 99.5 99.5 99.5 99.7 99.9 99.9
[14:14:17.745] INFO: X-ray hit rate [MHz/cm2]: 52.8 36.6 62.4 94.0 96.9 94.7 100.4 92.0 94.0 102.5 100.2 94.9 95.7 62.7 38.4 47.7
[14:14:17.745] INFO: PixTestHighRate::doXPixelAlive() done
[14:14:17.791] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:14:17.791] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:14:17.791] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:14:17.791] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:14:17.791] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:14:17.791] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:14:17.791] INFO: PixTest:: pg_setup set to default.
[14:14:30.357] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:14:30.357] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C13_V0 -> highRate_xraymap_mod
[14:14:34.125] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[14:14:34.126] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 387.5mA
[14:14:34.127] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 474.3mA
[14:14:34.127] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[14:14:34.127] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[14:14:34.127] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[14:14:34.127] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[14:14:34.127] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[14:14:34.127] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[14:14:34.127] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[14:14:34.127] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[14:14:34.127] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[14:14:34.127] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[14:14:34.127] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:14:34.220] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[14:14:34.220] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:14:34.234] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[14:14:34.234] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[14:14:34.234] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[14:14:34.234] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[14:14:34.234] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[14:14:34.234] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:14:34.237] QUIET: Connection to board 58 closed.
[14:14:34.316] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries