[12:34:42.242] INFO: *** Welcome to pxar ***
[12:34:42.242] INFO: *** Today: 2016/09/07
[12:34:42.266] INFO: *** Version: v1.9.0-818-g96727
[12:34:42.266] INFO: readRocDacs: data/mq616/dacParameters35_C0.dat .. data/mq616/dacParameters35_C15.dat
[12:34:42.267] INFO: readTbmDacs: data/mq616/tbmParameters_C0a.dat .. data/mq616/tbmParameters_C0b.dat
[12:34:42.268] INFO: readMaskFile: data/mq616/defaultMaskFile.dat
[12:34:42.268] INFO: readTrimFile: data/mq616/trimParameters35_C0.dat .. data/mq616/trimParameters35_C15.dat
[12:34:42.419] INFO: clk: 4
[12:34:42.419] INFO: ctr: 4
[12:34:42.419] INFO: sda: 19
[12:34:42.419] INFO: tin: 9
[12:34:42.419] INFO: level: 15
[12:34:42.419] INFO: triggerdelay: 0
[12:34:42.419] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:34:42.419] INFO: Log level: DEBUG
[12:34:42.430] QUIET: Connection to board DTB_WRPRHI opened.
[12:34:42.433] 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:
------------------------------------------------------
[12:34:42.436] INFO: RPC call hashes of host and DTB match: 398089610
[12:34:44.045] INFO: DUT info:
[12:34:44.046] INFO: The DUT currently contains the following objects:
[12:34:44.046] INFO: 2 TBM Cores tbm08c (2 ON)
[12:34:44.046] INFO: TBM Core alpha (0): 7 registers set
[12:34:44.046] INFO: TBM Core beta (1): 7 registers set
[12:34:44.046] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:34:44.046] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.046] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.046] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.046] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.046] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.046] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.046] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.046] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.046] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.046] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.046] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.046] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.046] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.046] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.046] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.046] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:34:44.047] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:34:44.048] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:34:44.049] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:34:44.050] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:34:44.050] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:34:44.050] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:34:44.050] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:34:44.050] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:34:44.050] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:34:44.050] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:34:44.050] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:34:44.050] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:34:44.050] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:34:44.050] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:34:44.052] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30330880
[12:34:44.052] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x27140e0
[12:34:44.052] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x24b6370
[12:34:44.052] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f7475d94010
[12:34:44.052] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f747bfff510
[12:34:44.052] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30339072 fPxarMemory = 0x7f7475d94010
[12:34:44.053] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 380.2mA
[12:34:44.054] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 464.7mA
[12:34:44.055] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[12:34:44.554] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2b03070
[12:34:44.620] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:34:44.620] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:34:44.620] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:34:44.626] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:34:44.626] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:34:44.626] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:34:44.637] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:34:44.637] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:34:44.650] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:34:44.650] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:34:44.669] INFO: PixTestCmd::init()
[12:34:44.688] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:34:44.688] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:34:44.688] INFO: readGainPedestalParameters data/mq616/phCalibrationFitErr35_C0.dat .. data/mq616/phCalibrationFitErr35_C15.dat
[12:34:44.941] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:34:44.941] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:34:44.959] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:34:44.966] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:34:44.966] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:34:44.966] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:34:44.987] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:34:44.987] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:34:44.987] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:34:44.987] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:34:44.987] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:34:44.994] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:34:44.994] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:34:45.009] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:34:45.009] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:34:45.009] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:34:45.009] INFO: readReadbackCal: data/mq616/readbackCal_C0.dat .. data/mq616/readbackCal_C15.dat
[12:34:45.019] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:34:45.029] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:34:45.029] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:34:45.029] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:34:45.029] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:34:45.045] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:34:45.045] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:35:00.806] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[12:35:01.566] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[12:35:01.734] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[12:35:03.982] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[12:35:04.854] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[12:35:04.854] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[12:35:04.854] INFO: ----------------------------------------------------------------------
[12:35:04.854] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[12:35:04.854] INFO: ----------------------------------------------------------------------
[12:35:05.828] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[12:35:18.576] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[12:35:18.580] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:35:45.859] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1255948 events.
[12:35:51.757] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1255948, pixels seen in all events: 5181066
[12:35:51.868] INFO: Resuming triggers.
[12:36:04.617] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[12:36:04.719] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:36:32.059] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1256024 events.
[12:36:37.855] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1256024, pixels seen in all events: 5179210
[12:36:38.090] INFO: Resuming triggers.
[12:36:50.838] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[12:36:50.878] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:37:17.981] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1255889 events.
[12:37:23.814] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1255889, pixels seen in all events: 5180304
[12:37:24.049] INFO: Resuming triggers.
[12:37:36.800] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[12:37:36.841] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:38:04.085] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1256172 events.
[12:38:09.985] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1256172, pixels seen in all events: 5176719
[12:38:10.162] INFO: Resuming triggers.
[12:38:19.243] INFO: data taking finished, elapsed time: 60 seconds.
[12:38:19.439] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer


















[12:38:38.797] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 894615 events.
[12:38:42.964] DEBUG: <PixTestXray.cc/processData:L823> # events read: 894615, pixels seen in all events: 3688466
[12:38:43.083] INFO: PixTest:: pg_setup set to default.
[12:38:43.174] INFO: PixTestXray::doPhRun() done
[12:39:15.326] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:39:16.798] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:39:19.254] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:39:19.254] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = qMap_Ag_C14_V0 -> qMap_Ag_mod
[12:39:24.622] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:39:24.623] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.9mA
[12:39:24.624] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 464.7mA
[12:39:24.624] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[12:39:24.624] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:39:24.624] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:39:24.624] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:39:24.624] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:39:24.624] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:39:24.624] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:39:24.624] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:39:24.624] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:39:24.624] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:39:24.625] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:39:24.625] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:39:24.625] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:39:24.625] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:39:24.625] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:39:24.625] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:39:24.625] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:39:24.625] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:39:24.625] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:39:24.943] QUIET: Connection to board 58 closed.
[12:39:25.023] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[12:40:13.657] INFO: *** Welcome to pxar ***
[12:40:13.657] INFO: *** Today: 2016/09/07
[12:40:13.685] INFO: *** Version: v1.9.0-818-g96727
[12:40:13.685] INFO: readRocDacs: data/mq616/dacParameters35_C0.dat .. data/mq616/dacParameters35_C15.dat
[12:40:13.686] INFO: readTbmDacs: data/mq616/tbmParameters_C0a.dat .. data/mq616/tbmParameters_C0b.dat
[12:40:13.686] INFO: readMaskFile: data/mq616/defaultMaskFile.dat
[12:40:13.686] INFO: readTrimFile: data/mq616/trimParameters35_C0.dat .. data/mq616/trimParameters35_C15.dat
[12:40:13.836] INFO: clk: 4
[12:40:13.836] INFO: ctr: 4
[12:40:13.836] INFO: sda: 19
[12:40:13.836] INFO: tin: 9
[12:40:13.836] INFO: level: 15
[12:40:13.836] INFO: triggerdelay: 0
[12:40:13.836] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:40:13.836] INFO: Log level: DEBUG
[12:40:13.847] QUIET: Connection to board DTB_WRPRHI opened.
[12:40:13.850] 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:
------------------------------------------------------
[12:40:13.853] INFO: RPC call hashes of host and DTB match: 398089610
[12:40:15.456] INFO: DUT info:
[12:40:15.456] INFO: The DUT currently contains the following objects:
[12:40:15.456] INFO: 2 TBM Cores tbm08c (2 ON)
[12:40:15.456] INFO: TBM Core alpha (0): 7 registers set
[12:40:15.456] INFO: TBM Core beta (1): 7 registers set
[12:40:15.456] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:40:15.456] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.456] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.456] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.456] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.456] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.456] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.456] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.456] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.456] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.456] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.456] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.456] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.456] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.456] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.456] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.456] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:40:15.457] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:40:15.458] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:40:15.459] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:40:15.460] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:40:15.460] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:40:15.460] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:40:15.460] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:40:15.460] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:40:15.460] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:40:15.460] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:40:15.460] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:40:15.460] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:40:15.460] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:40:15.460] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:40:15.462] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30334976
[12:40:15.462] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1a970e0
[12:40:15.462] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1839370
[12:40:15.462] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f57f1d94010
[12:40:15.462] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f57f7fff510
[12:40:15.462] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30343168 fPxarMemory = 0x7f57f1d94010
[12:40:15.463] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 380.2mA
[12:40:15.464] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 464.7mA
[12:40:15.464] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[12:40:15.962] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1e85f40
[12:40:16.025] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:40:16.025] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:40:16.026] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:40:16.031] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:40:16.031] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:40:16.031] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:40:16.042] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:40:16.042] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:40:16.060] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:40:16.060] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:40:16.081] INFO: PixTestCmd::init()
[12:40:16.096] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:40:16.097] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:40:16.097] INFO: readGainPedestalParameters data/mq616/phCalibrationFitErr35_C0.dat .. data/mq616/phCalibrationFitErr35_C15.dat
[12:40:16.343] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:40:16.343] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:40:16.358] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:40:16.365] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:40:16.365] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:40:16.365] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:40:16.387] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:40:16.387] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:40:16.387] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:40:16.387] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:40:16.387] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:40:16.394] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:40:16.394] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:40:16.416] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:40:16.416] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:40:16.416] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:40:16.416] INFO: readReadbackCal: data/mq616/readbackCal_C0.dat .. data/mq616/readbackCal_C15.dat
[12:40:16.427] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:40:16.434] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:40:16.435] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:40:16.435] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:40:16.435] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:40:16.453] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:40:16.453] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:40:29.222] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[12:40:30.262] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[12:40:31.310] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[12:40:31.310] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[12:40:31.310] INFO: ----------------------------------------------------------------------
[12:40:31.310] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[12:40:31.311] INFO: ----------------------------------------------------------------------
[12:40:32.286] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[12:40:40.582] INFO: run duration 8 seconds, buffer almost full (81%), pausing triggers.
[12:40:40.585] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:41:06.048] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 817401 events.
[12:41:17.587] DEBUG: <PixTestXray.cc/processData:L823> # events read: 817401, pixels seen in all events: 10444188
[12:41:17.811] INFO: Resuming triggers.
[12:41:26.109] INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[12:41:26.158] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:41:51.677] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 817485 events.
[12:42:03.192] DEBUG: <PixTestXray.cc/processData:L823> # events read: 817485, pixels seen in all events: 10441390
[12:42:03.372] INFO: Resuming triggers.
[12:42:11.675] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[12:42:11.791] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:42:37.120] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 817904 events.
[12:42:48.614] DEBUG: <PixTestXray.cc/processData:L823> # events read: 817904, pixels seen in all events: 10436765
[12:42:48.794] INFO: Resuming triggers.
[12:42:57.099] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[12:42:57.213] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer


















[12:43:22.636] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 818187 events.
[12:43:34.275] DEBUG: <PixTestXray.cc/processData:L823> # events read: 818187, pixels seen in all events: 10433845
[12:43:34.499] INFO: Resuming triggers.
[12:43:42.801] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[12:43:42.842] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:44:08.539] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 817842 events.
[12:44:20.080] DEBUG: <PixTestXray.cc/processData:L823> # events read: 817842, pixels seen in all events: 10437890
[12:44:20.341] INFO: Resuming triggers.
[12:44:28.646] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[12:44:28.695] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer









[12:44:54.128] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 818093 events.
[12:45:05.732] DEBUG: <PixTestXray.cc/processData:L823> # events read: 818093, pixels seen in all events: 10434258
[12:45:05.995] INFO: Resuming triggers.
[12:45:14.301] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[12:45:14.341] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

















[12:45:39.807] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 818235 events.
[12:45:51.307] DEBUG: <PixTestXray.cc/processData:L823> # events read: 818235, pixels seen in all events: 10433079
[12:45:51.566] INFO: Resuming triggers.
[12:45:53.528] INFO: data taking finished, elapsed time: 60 seconds.
[12:45:53.724] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:45:59.898] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 193195 events.
[12:46:02.619] DEBUG: <PixTestXray.cc/processData:L823> # events read: 193195, pixels seen in all events: 2465478
[12:46:02.689] INFO: PixTest:: pg_setup set to default.
[12:46:02.738] INFO: PixTestXray::doPhRun() done
[12:47:21.366] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:47:21.367] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.9mA
[12:47:21.368] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 465.5mA
[12:47:21.368] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[12:47:21.368] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:47:21.368] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:47:21.368] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:47:21.368] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:47:21.368] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:47:21.369] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:47:21.369] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:47:21.369] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:47:21.369] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:47:21.369] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:47:21.369] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:47:21.369] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:47:21.369] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:47:21.369] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:47:21.369] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:47:21.369] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:47:21.369] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:47:21.369] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:47:21.681] QUIET: Connection to board 58 closed.
[12:47:21.760] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[12:27:28.925] INFO: *** Welcome to pxar ***
[12:27:28.925] INFO: *** Today: 2016/09/07
[12:27:28.967] INFO: *** Version: v1.9.0-818-g96727
[12:27:28.967] INFO: readRocDacs: data/mq616/dacParameters35_C0.dat .. data/mq616/dacParameters35_C15.dat
[12:27:28.968] INFO: readTbmDacs: data/mq616/tbmParameters_C0a.dat .. data/mq616/tbmParameters_C0b.dat
[12:27:28.969] INFO: readMaskFile: data/mq616/defaultMaskFile.dat
[12:27:28.969] INFO: readTrimFile: data/mq616/trimParameters35_C0.dat .. data/mq616/trimParameters35_C15.dat
[12:27:29.121] INFO: clk: 4
[12:27:29.121] INFO: ctr: 4
[12:27:29.121] INFO: sda: 19
[12:27:29.121] INFO: tin: 9
[12:27:29.121] INFO: level: 15
[12:27:29.121] INFO: triggerdelay: 0
[12:27:29.121] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:27:29.121] INFO: Log level: DEBUG
[12:27:29.131] QUIET: Connection to board DTB_WRPRHI opened.
[12:27:29.135] 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:
------------------------------------------------------
[12:27:29.138] INFO: RPC call hashes of host and DTB match: 398089610
[12:27:30.739] INFO: DUT info:
[12:27:30.739] INFO: The DUT currently contains the following objects:
[12:27:30.739] INFO: 2 TBM Cores tbm08c (2 ON)
[12:27:30.739] INFO: TBM Core alpha (0): 7 registers set
[12:27:30.739] INFO: TBM Core beta (1): 7 registers set
[12:27:30.739] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:27:30.739] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.739] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.739] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.739] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.739] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.739] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.739] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.739] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.740] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.740] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.740] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.740] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.740] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.740] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.740] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.740] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:27:30.740] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:27:30.741] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:27:30.742] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:27:30.743] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:27:30.745] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31412224
[12:27:30.745] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x20100e0
[12:27:30.745] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1db2370
[12:27:30.745] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fc30d393010
[12:27:30.745] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fc3135fe510
[12:27:30.745] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31420416 fPxarMemory = 0x7fc30d393010
[12:27:30.746] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 380.2mA
[12:27:30.747] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 464.7mA
[12:27:30.747] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[12:27:31.245] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x23ff070
[12:27:31.351] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:27:31.351] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:27:31.352] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:27:31.359] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:27:31.359] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:27:31.359] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:27:31.372] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:27:31.372] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:27:31.392] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:27:31.392] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:27:31.414] INFO: PixTestCmd::init()
[12:27:31.429] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:27:31.429] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:27:31.429] INFO: readGainPedestalParameters data/mq616/phCalibrationFitErr35_C0.dat .. data/mq616/phCalibrationFitErr35_C15.dat
[12:27:31.683] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:27:31.683] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:27:31.697] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:27:31.703] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:27:31.703] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:27:31.703] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:27:31.728] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:27:31.728] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:27:31.728] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:27:31.728] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:27:31.728] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:27:31.735] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:27:31.735] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:27:31.756] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:27:31.756] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:27:31.756] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:27:31.756] INFO: readReadbackCal: data/mq616/readbackCal_C0.dat .. data/mq616/readbackCal_C15.dat
[12:27:31.766] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:27:31.774] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:27:31.774] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:27:31.774] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:27:31.774] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:27:31.794] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:27:31.794] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:27:42.705] INFO: ######################################################################
[12:27:42.705] INFO: PixTestAlive::doTest()
[12:27:42.705] INFO: ######################################################################
[12:27:42.709] INFO: ----------------------------------------------------------------------
[12:27:42.709] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:27:42.709] INFO: ----------------------------------------------------------------------
[12:27:42.711] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:27:43.090] INFO: Expecting 41600 events.
[12:27:47.479] INFO: 41600 events read in total (3671ms).
[12:27:47.626] INFO: Test took 4915ms.
[12:27:47.637] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:47.637] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[12:27:47.637] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[12:27:47.915] INFO: PixTestAlive::aliveTest() done
[12:27:47.915] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0
[12:27:47.915] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 26 13 23 31 29 34 42 26 32 42 45 31 35 22 14 22
[12:27:47.956] INFO: ----------------------------------------------------------------------
[12:27:47.956] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:27:47.956] INFO: ----------------------------------------------------------------------
[12:27:47.959] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:27:48.336] INFO: Expecting 41600 events.
[12:27:51.448] INFO: 41600 events read in total (2397ms).
[12:27:51.449] INFO: Test took 3490ms.
[12:27:51.449] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:51.449] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[12:27:51.449] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[12:27:51.450] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:27:51.872] INFO: PixTestAlive::maskTest() done
[12:27:51.872] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:27:51.894] INFO: ----------------------------------------------------------------------
[12:27:51.894] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:27:51.894] INFO: ----------------------------------------------------------------------
[12:27:51.897] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:27:52.274] INFO: Expecting 41600 events.
[12:27:56.665] INFO: 41600 events read in total (3675ms).
[12:27:56.666] INFO: Test took 4769ms.
[12:27:56.678] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:56.678] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[12:27:56.678] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[12:27:57.047] INFO: PixTestAlive::addressDecodingTest() done
[12:27:57.047] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:27:57.047] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[12:28:35.678] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:28:35.678] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = AddressDecodingTest_C8_V0 -> AddressDecodingTest_mod
[12:28:45.958] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[12:28:45.958] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[12:28:45.958] INFO: ----------------------------------------------------------------------
[12:28:45.958] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:28:45.958] INFO: ----------------------------------------------------------------------
[12:28:46.098] INFO: Expecting 768 events.
[12:28:47.232] INFO: 768 events read in total (419ms).
[12:28:47.232] INFO: Test took 1268ms.
[12:28:47.238] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:28:48.035] INFO: Expecting 41600 events.
[12:28:51.303] INFO: 41600 events read in total (2741ms).
[12:28:51.307] INFO: Test took 4069ms.
[12:28:51.381] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:51.381] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 410377
[12:28:51.381] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[12:28:51.381] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:28:51.416] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:28:52.081] INFO: Expecting 41600 events.
[12:28:55.483] INFO: 41600 events read in total (2875ms).
[12:28:55.486] INFO: Test took 4071ms.
[12:28:55.557] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:55.557] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 421236
[12:28:55.557] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[12:28:55.558] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:28:55.594] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:28:56.261] INFO: Expecting 41600 events.
[12:28:59.719] INFO: 41600 events read in total (2931ms).
[12:28:59.722] INFO: Test took 4128ms.
[12:28:59.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:59.793] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 424052
[12:28:59.793] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[12:28:59.794] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:28:59.830] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:00.495] INFO: Expecting 41600 events.
[12:29:03.961] INFO: 41600 events read in total (2940ms).
[12:29:03.965] INFO: Test took 4135ms.
[12:29:04.034] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:04.034] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 425042
[12:29:04.034] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[12:29:04.035] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:29:04.071] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:04.736] INFO: Expecting 41600 events.
[12:29:08.202] INFO: 41600 events read in total (2940ms).
[12:29:08.205] INFO: Test took 4134ms.
[12:29:08.277] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:08.277] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 424845
[12:29:08.278] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[12:29:08.278] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:29:08.314] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:08.979] INFO: Expecting 41600 events.
[12:29:12.442] INFO: 41600 events read in total (2936ms).
[12:29:12.446] INFO: Test took 4132ms.
[12:29:12.516] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:12.516] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 425304
[12:29:12.516] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[12:29:12.516] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:29:12.553] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:13.220] INFO: Expecting 41600 events.
[12:29:16.707] INFO: 41600 events read in total (2960ms).
[12:29:16.711] INFO: Test took 4158ms.
[12:29:16.782] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:16.782] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 424417
[12:29:16.782] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[12:29:16.782] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:29:16.819] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:17.483] INFO: Expecting 41600 events.
[12:29:20.955] INFO: 41600 events read in total (2945ms).
[12:29:20.959] INFO: Test took 4140ms.
[12:29:21.030] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:21.030] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 425388
[12:29:21.030] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[12:29:21.031] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:29:21.067] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:21.736] INFO: Expecting 41600 events.
[12:29:25.222] INFO: 41600 events read in total (2960ms).
[12:29:25.225] INFO: Test took 4158ms.
[12:29:25.296] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:25.296] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 424613
[12:29:25.297] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[12:29:25.297] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:29:25.335] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:26.000] INFO: Expecting 41600 events.
[12:29:29.490] INFO: 41600 events read in total (2964ms).
[12:29:29.494] INFO: Test took 4159ms.
[12:29:29.565] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:29.565] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 425369
[12:29:29.565] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[12:29:29.566] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:29:29.604] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:30.272] INFO: Expecting 41600 events.
[12:29:33.751] INFO: 41600 events read in total (2952ms).
[12:29:33.754] INFO: Test took 4150ms.
[12:29:33.826] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:33.826] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 425114
[12:29:33.826] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[12:29:33.827] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:29:33.865] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:34.530] INFO: Expecting 41600 events.
[12:29:38.004] INFO: 41600 events read in total (2948ms).
[12:29:38.007] INFO: Test took 4142ms.
[12:29:38.078] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:38.078] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 423879
[12:29:38.078] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[12:29:38.079] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:29:38.115] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:38.780] INFO: Expecting 41600 events.
[12:29:42.263] INFO: 41600 events read in total (2956ms).
[12:29:42.266] INFO: Test took 4151ms.
[12:29:42.337] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:42.338] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 424452
[12:29:42.338] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[12:29:42.338] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:29:42.374] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:43.040] INFO: Expecting 41600 events.
[12:29:46.517] INFO: 41600 events read in total (2951ms).
[12:29:46.520] INFO: Test took 4146ms.
[12:29:46.591] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:46.591] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 425684
[12:29:46.591] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[12:29:46.592] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:29:46.628] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:47.293] INFO: Expecting 41600 events.
[12:29:50.768] INFO: 41600 events read in total (2948ms).
[12:29:50.771] INFO: Test took 4143ms.
[12:29:50.842] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:50.842] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 424656
[12:29:50.842] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[12:29:50.843] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:29:50.879] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:51.544] INFO: Expecting 41600 events.
[12:29:55.028] INFO: 41600 events read in total (2957ms).
[12:29:55.032] INFO: Test took 4153ms.
[12:29:55.102] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:55.102] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 424237
[12:29:55.103] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[12:29:55.103] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:29:55.140] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:55.805] INFO: Expecting 41600 events.
[12:29:59.280] INFO: 41600 events read in total (2948ms).
[12:29:59.283] INFO: Test took 4143ms.
[12:29:59.353] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:59.353] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 424725
[12:29:59.353] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[12:29:59.354] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:29:59.391] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:00.056] INFO: Expecting 41600 events.
[12:30:03.530] INFO: 41600 events read in total (2947ms).
[12:30:03.533] INFO: Test took 4142ms.
[12:30:03.603] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:03.603] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 423774
[12:30:03.603] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[12:30:03.604] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:03.641] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:04.306] INFO: Expecting 41600 events.
[12:30:07.757] INFO: 41600 events read in total (2924ms).
[12:30:07.760] INFO: Test took 4119ms.
[12:30:07.830] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:07.830] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 423478
[12:30:07.830] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[12:30:07.831] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:07.867] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:08.532] INFO: Expecting 41600 events.
[12:30:11.870] INFO: 41600 events read in total (2811ms).
[12:30:11.873] INFO: Test took 4006ms.
[12:30:11.943] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:11.943] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 419851
[12:30:11.943] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[12:30:11.943] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:12.201] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 158 eff = 0.999663
[12:30:12.201] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 141 eff = 0.999832
[12:30:12.201] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 163 eff = 0.999423
[12:30:12.201] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 143 eff = 0.999207
[12:30:12.201] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 176 eff = 0.999135
[12:30:12.201] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 141 eff = 0.999063
[12:30:12.202] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 128 eff = 0.998942
[12:30:12.202] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 155 eff = 0.999615
[12:30:12.202] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 155 eff = 0.999279
[12:30:12.202] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 162 eff = 0.999038
[12:30:12.202] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 132 eff = 0.998918
[12:30:12.202] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 144 eff = 0.999207
[12:30:12.203] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 147 eff = 0.999231
[12:30:12.203] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 159 eff = 0.999423
[12:30:12.203] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 138 eff = 0.99976
[12:30:12.203] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 143 eff = 0.999688
[12:30:19.526] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[12:30:19.526] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[12:30:19.526] INFO: ----------------------------------------------------------------------
[12:30:19.526] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:30:19.526] INFO: ----------------------------------------------------------------------
[12:30:19.527] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[12:30:19.527] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[12:30:19.527] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[12:30:19.527] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[12:30:19.527] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[12:30:19.527] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[12:30:19.527] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[12:30:19.527] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[12:30:19.527] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[12:30:19.527] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[12:30:19.527] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[12:30:19.527] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[12:30:19.544] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:20.211] INFO: Expecting 208000 events.
[12:30:33.317] INFO: 208000 events read in total (12579ms).
[12:30:33.327] INFO: Test took 13783ms.
[12:30:33.646] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:33.646] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1843943
[12:30:33.646] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[12:30:33.647] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:34.002] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0
[12:30:34.002] INFO: number of red-efficiency pixels: 114 66 122 220 219 218 218 143 167 250 292 216 221 159 68 83
[12:30:34.002] INFO: number of X-ray hits detected: 83369 55397 92912 134634 142723 140184 135300 98087 95615 151477 149786 133883 139693 95497 56618 72209
[12:30:34.002] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:30:34.002] INFO: number of Vcal hits detected: 207882 207933 207869 207777 207773 207773 207723 207853 207830 207742 207697 207774 207772 207835 207930 207916
[12:30:34.002] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[12:30:34.002] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[12:30:34.002] INFO: X-ray hit rate [MHz/cm2]: 24.4 16.2 27.2 39.5 41.8 41.1 39.7 28.8 28.0 44.4 43.9 39.2 40.9 28.0 16.6 21.2
[12:30:34.002] INFO: PixTestHighRate::doXPixelAlive() done
[12:30:34.049] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[12:30:34.049] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[12:30:34.049] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[12:30:34.049] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[12:30:34.049] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[12:30:34.049] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[12:30:34.049] INFO: PixTest:: pg_setup set to default.
[12:31:19.910] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:31:19.910] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C13_V0 -> highRate_xraymap_mod
[12:31:34.646] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:31:34.647] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.9mA
[12:31:34.648] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 464.7mA
[12:31:34.648] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[12:31:34.648] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:31:34.648] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:31:34.648] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:31:34.648] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:31:34.648] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:31:34.648] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:31:34.648] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:31:34.649] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:31:34.649] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:31:34.649] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:31:34.737] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:31:34.737] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:31:34.750] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:31:34.750] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:31:34.750] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:31:34.750] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:31:34.750] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:31:34.750] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:31:34.753] QUIET: Connection to board 58 closed.
[12:31:34.833] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[12:23:41.944] INFO: *** Welcome to pxar ***
[12:23:41.944] INFO: *** Today: 2016/09/07
[12:23:41.968] INFO: *** Version: v1.9.0-818-g96727
[12:23:41.968] INFO: readRocDacs: data/mq616/dacParameters35_C0.dat .. data/mq616/dacParameters35_C15.dat
[12:23:41.969] INFO: readTbmDacs: data/mq616/tbmParameters_C0a.dat .. data/mq616/tbmParameters_C0b.dat
[12:23:41.970] INFO: readMaskFile: data/mq616/defaultMaskFile.dat
[12:23:41.970] INFO: readTrimFile: data/mq616/trimParameters35_C0.dat .. data/mq616/trimParameters35_C15.dat
[12:23:42.121] INFO: clk: 4
[12:23:42.121] INFO: ctr: 4
[12:23:42.122] INFO: sda: 19
[12:23:42.122] INFO: tin: 9
[12:23:42.122] INFO: level: 15
[12:23:42.122] INFO: triggerdelay: 0
[12:23:42.122] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:23:42.122] INFO: Log level: DEBUG
[12:23:42.132] QUIET: Connection to board DTB_WRPRHI opened.
[12:23:42.136] 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:
------------------------------------------------------
[12:23:42.138] INFO: RPC call hashes of host and DTB match: 398089610
[12:23:43.743] INFO: DUT info:
[12:23:43.743] INFO: The DUT currently contains the following objects:
[12:23:43.743] INFO: 2 TBM Cores tbm08c (2 ON)
[12:23:43.743] INFO: TBM Core alpha (0): 7 registers set
[12:23:43.743] INFO: TBM Core beta (1): 7 registers set
[12:23:43.743] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:23:43.743] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.743] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.743] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.743] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.743] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.743] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.743] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.743] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.743] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.743] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.743] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.743] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.744] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.744] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.744] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.744] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:23:43.744] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:23:43.745] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:23:43.746] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:23:43.747] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:23:43.749] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31379456
[12:23:43.749] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2a7b0e0
[12:23:43.749] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x281d370
[12:23:43.749] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f9e69d94010
[12:23:43.749] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f9e6ffff510
[12:23:43.749] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31387648 fPxarMemory = 0x7f9e69d94010
[12:23:43.751] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 380.2mA
[12:23:43.752] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[12:23:43.752] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[12:23:44.249] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2e6a010
[12:23:44.312] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:23:44.312] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:23:44.312] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:23:44.318] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:23:44.318] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:23:44.318] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:23:44.329] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:23:44.329] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:23:44.350] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:23:44.350] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:23:44.364] INFO: PixTestCmd::init()
[12:23:44.382] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:23:44.382] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:23:44.383] INFO: readGainPedestalParameters data/mq616/phCalibrationFitErr35_C0.dat .. data/mq616/phCalibrationFitErr35_C15.dat
[12:23:44.632] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:23:44.632] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:23:44.647] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:23:44.656] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:23:44.657] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:23:44.657] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:23:44.686] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:23:44.686] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:23:44.686] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:23:44.686] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:23:44.686] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:23:44.693] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:23:44.693] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:23:44.713] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:23:44.713] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:23:44.713] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:23:44.713] INFO: readReadbackCal: data/mq616/readbackCal_C0.dat .. data/mq616/readbackCal_C15.dat
[12:23:44.723] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:23:44.737] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:23:44.737] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:23:44.737] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:23:44.737] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:23:44.753] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:23:44.753] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:23:55.009] INFO: ######################################################################
[12:23:55.009] INFO: PixTestAlive::doTest()
[12:23:55.009] INFO: ######################################################################
[12:23:55.013] INFO: ----------------------------------------------------------------------
[12:23:55.013] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:23:55.013] INFO: ----------------------------------------------------------------------
[12:23:55.015] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:23:55.395] INFO: Expecting 41600 events.
[12:23:59.779] INFO: 41600 events read in total (3666ms).
[12:23:59.926] INFO: Test took 4911ms.
[12:23:59.938] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:59.938] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[12:23:59.938] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[12:24:00.209] INFO: PixTestAlive::aliveTest() done
[12:24:00.209] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0
[12:24:00.209] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 27 29 31 50 61 60 58 33 36 68 47 47 63 34 31 27
[12:24:00.235] INFO: ----------------------------------------------------------------------
[12:24:00.235] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:24:00.235] INFO: ----------------------------------------------------------------------
[12:24:00.238] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:00.616] INFO: Expecting 41600 events.
[12:24:03.739] INFO: 41600 events read in total (2408ms).
[12:24:03.739] INFO: Test took 3501ms.
[12:24:03.740] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:03.740] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[12:24:03.740] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[12:24:03.740] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:24:04.162] INFO: PixTestAlive::maskTest() done
[12:24:04.162] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:24:04.185] INFO: ----------------------------------------------------------------------
[12:24:04.185] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:24:04.185] INFO: ----------------------------------------------------------------------
[12:24:04.193] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:04.572] INFO: Expecting 41600 events.
[12:24:08.960] INFO: 41600 events read in total (3673ms).
[12:24:08.962] INFO: Test took 4769ms.
[12:24:08.973] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:08.973] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[12:24:08.973] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[12:24:09.343] INFO: PixTestAlive::addressDecodingTest() done
[12:24:09.343] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:24:09.343] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[12:24:20.310] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[12:24:20.310] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[12:24:20.310] INFO: ----------------------------------------------------------------------
[12:24:20.310] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:24:20.310] INFO: ----------------------------------------------------------------------
[12:24:20.449] INFO: Expecting 768 events.
[12:24:21.584] INFO: 768 events read in total (419ms).
[12:24:21.584] INFO: Test took 1268ms.
[12:24:21.590] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:22.387] INFO: Expecting 41600 events.
[12:24:25.878] INFO: 41600 events read in total (2964ms).
[12:24:25.882] INFO: Test took 4292ms.
[12:24:25.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:25.994] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 612558
[12:24:25.994] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[12:24:25.995] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:26.042] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:26.717] INFO: Expecting 41600 events.
[12:24:30.346] INFO: 41600 events read in total (3102ms).
[12:24:30.352] INFO: Test took 4310ms.
[12:24:30.459] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:30.459] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 622297
[12:24:30.459] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[12:24:30.460] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:30.510] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:31.175] INFO: Expecting 41600 events.
[12:24:34.847] INFO: 41600 events read in total (3145ms).
[12:24:34.851] INFO: Test took 4341ms.
[12:24:34.958] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:34.958] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 625995
[12:24:34.958] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[12:24:34.959] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:35.011] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:35.688] INFO: Expecting 41600 events.
[12:24:39.353] INFO: 41600 events read in total (3138ms).
[12:24:39.357] INFO: Test took 4346ms.
[12:24:39.465] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:39.465] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 625273
[12:24:39.465] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[12:24:39.465] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:39.517] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:40.184] INFO: Expecting 41600 events.
[12:24:43.863] INFO: 41600 events read in total (3153ms).
[12:24:43.867] INFO: Test took 4350ms.
[12:24:43.975] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:43.976] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 625236
[12:24:43.976] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[12:24:43.976] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:44.027] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:44.694] INFO: Expecting 41600 events.
[12:24:48.374] INFO: 41600 events read in total (3153ms).
[12:24:48.378] INFO: Test took 4351ms.
[12:24:48.486] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:48.486] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 626191
[12:24:48.486] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[12:24:48.486] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:48.537] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:49.204] INFO: Expecting 41600 events.
[12:24:52.900] INFO: 41600 events read in total (3170ms).
[12:24:52.904] INFO: Test took 4367ms.
[12:24:53.011] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:53.011] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 626546
[12:24:53.011] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[12:24:53.011] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:53.060] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:53.726] INFO: Expecting 41600 events.
[12:24:57.429] INFO: 41600 events read in total (3177ms).
[12:24:57.433] INFO: Test took 4372ms.
[12:24:57.541] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:57.541] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 626622
[12:24:57.541] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[12:24:57.541] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:57.592] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:58.259] INFO: Expecting 41600 events.
[12:25:01.957] INFO: 41600 events read in total (3171ms).
[12:25:01.961] INFO: Test took 4368ms.
[12:25:02.067] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:02.067] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 627235
[12:25:02.067] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[12:25:02.068] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:02.117] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:02.782] INFO: Expecting 41600 events.
[12:25:06.483] INFO: 41600 events read in total (3174ms).
[12:25:06.487] INFO: Test took 4370ms.
[12:25:06.593] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:06.594] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 625302
[12:25:06.594] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[12:25:06.594] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:06.644] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:07.309] INFO: Expecting 41600 events.
[12:25:11.014] INFO: 41600 events read in total (3178ms).
[12:25:11.019] INFO: Test took 4375ms.
[12:25:11.125] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:11.125] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 626432
[12:25:11.125] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[12:25:11.126] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:11.177] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:11.844] INFO: Expecting 41600 events.
[12:25:15.528] INFO: 41600 events read in total (3157ms).
[12:25:15.533] INFO: Test took 4356ms.
[12:25:15.640] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:15.640] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 627253
[12:25:15.640] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[12:25:15.640] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:15.692] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:16.359] INFO: Expecting 41600 events.
[12:25:20.043] INFO: 41600 events read in total (3157ms).
[12:25:20.047] INFO: Test took 4356ms.
[12:25:20.200] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:20.200] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 626598
[12:25:20.200] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[12:25:20.201] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:20.250] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:20.917] INFO: Expecting 41600 events.
[12:25:24.613] INFO: 41600 events read in total (3169ms).
[12:25:24.618] INFO: Test took 4368ms.
[12:25:24.725] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:24.725] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 627240
[12:25:24.725] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[12:25:24.725] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:24.775] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:25.440] INFO: Expecting 41600 events.
[12:25:29.134] INFO: 41600 events read in total (3167ms).
[12:25:29.138] INFO: Test took 4363ms.
[12:25:29.246] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:29.246] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 625308
[12:25:29.246] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[12:25:29.246] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:29.298] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:29.967] INFO: Expecting 41600 events.
[12:25:33.671] INFO: 41600 events read in total (3177ms).
[12:25:33.675] INFO: Test took 4377ms.
[12:25:33.779] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:33.779] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 625416
[12:25:33.779] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[12:25:33.780] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:33.829] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:34.495] INFO: Expecting 41600 events.
[12:25:38.189] INFO: 41600 events read in total (3168ms).
[12:25:38.193] INFO: Test took 4364ms.
[12:25:38.300] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:38.300] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 625745
[12:25:38.300] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[12:25:38.300] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:38.352] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:39.018] INFO: Expecting 41600 events.
[12:25:42.706] INFO: 41600 events read in total (3161ms).
[12:25:42.710] INFO: Test took 4358ms.
[12:25:42.815] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:42.815] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 625411
[12:25:42.815] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[12:25:42.815] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:42.865] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:43.531] INFO: Expecting 41600 events.
[12:25:47.205] INFO: 41600 events read in total (3147ms).
[12:25:47.209] INFO: Test took 4343ms.
[12:25:47.316] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:47.316] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 624495
[12:25:47.316] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[12:25:47.316] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:47.367] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:48.032] INFO: Expecting 41600 events.
[12:25:51.580] INFO: 41600 events read in total (3022ms).
[12:25:51.584] INFO: Test took 4217ms.
[12:25:51.690] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:51.690] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 622324
[12:25:51.690] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[12:25:51.691] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:51.963] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 148 eff = 0.999135
[12:25:51.964] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 138 eff = 0.999543
[12:25:51.964] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 161 eff = 0.998798
[12:25:51.964] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 143 eff = 0.997788
[12:25:51.964] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 189 eff = 0.997957
[12:25:51.965] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 146 eff = 0.997572
[12:25:51.965] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 137 eff = 0.997861
[12:25:51.965] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 140 eff = 0.99851
[12:25:51.965] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 154 eff = 0.998678
[12:25:51.966] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 164 eff = 0.997452
[12:25:51.966] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 158 eff = 0.997284
[12:25:51.966] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 146 eff = 0.998149
[12:25:51.966] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 147 eff = 0.997861
[12:25:51.967] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 169 eff = 0.998774
[12:25:51.967] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 115 eff = 0.999567
[12:25:51.967] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 120 eff = 0.999471
[12:26:05.070] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[12:26:05.070] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[12:26:05.070] INFO: ----------------------------------------------------------------------
[12:26:05.070] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:26:05.070] INFO: ----------------------------------------------------------------------
[12:26:05.071] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[12:26:05.071] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[12:26:05.071] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[12:26:05.071] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[12:26:05.071] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[12:26:05.071] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[12:26:05.071] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[12:26:05.071] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[12:26:05.071] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[12:26:05.071] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[12:26:05.071] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[12:26:05.071] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[12:26:05.088] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:26:05.757] INFO: Expecting 208000 events.
[12:26:20.403] INFO: 208000 events read in total (14119ms).
[12:26:20.418] INFO: Test took 15330ms.
[12:26:20.916] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:20.916] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2844899
[12:26:20.916] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[12:26:20.917] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:26:21.340] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0
[12:26:21.340] INFO: number of red-efficiency pixels: 230 143 275 505 437 431 465 344 361 493 445 416 451 279 130 158
[12:26:21.340] INFO: number of X-ray hits detected: 131319 86495 144999 209320 222950 219428 211394 155194 148908 237629 233727 209167 217383 149466 88502 112459
[12:26:21.340] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:26:21.340] INFO: number of Vcal hits detected: 207762 207853 207708 207445 207536 207455 207450 207607 207605 207470 207506 207544 207511 207706 207866 207836
[12:26:21.340] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[12:26:21.340] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.7 99.8 99.7 99.7 99.8 99.8 99.7 99.8 99.8 99.8 99.9 99.9 99.9
[12:26:21.340] INFO: X-ray hit rate [MHz/cm2]: 38.5 25.4 42.5 61.4 65.3 64.3 62.0 45.5 43.6 69.7 68.5 61.3 63.7 43.8 25.9 33.0
[12:26:21.340] INFO: PixTestHighRate::doXPixelAlive() done
[12:26:21.387] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[12:26:21.387] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[12:26:21.387] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[12:26:21.387] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[12:26:21.387] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[12:26:21.387] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[12:26:21.387] INFO: PixTest:: pg_setup set to default.
[12:27:09.366] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:27:09.366] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C13_V0 -> highRate_xraymap_mod
[12:27:12.894] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:27:12.895] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.9mA
[12:27:12.896] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 464.7mA
[12:27:12.896] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[12:27:12.896] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:27:12.896] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:27:12.896] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:27:12.896] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:27:12.896] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:27:12.896] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:27:12.896] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:27:12.896] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:27:12.896] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:27:12.896] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:27:12.989] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:27:12.989] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:27:12.998] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:27:12.998] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:27:12.998] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:27:12.998] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:27:12.998] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:27:12.998] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:27:13.001] QUIET: Connection to board 58 closed.
[12:27:13.080] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[12:19:50.820] INFO: *** Welcome to pxar ***
[12:19:50.820] INFO: *** Today: 2016/09/07
[12:19:50.844] INFO: *** Version: v1.9.0-818-g96727
[12:19:50.844] INFO: readRocDacs: data/mq616/dacParameters35_C0.dat .. data/mq616/dacParameters35_C15.dat
[12:19:50.845] INFO: readTbmDacs: data/mq616/tbmParameters_C0a.dat .. data/mq616/tbmParameters_C0b.dat
[12:19:50.845] INFO: readMaskFile: data/mq616/defaultMaskFile.dat
[12:19:50.845] INFO: readTrimFile: data/mq616/trimParameters35_C0.dat .. data/mq616/trimParameters35_C15.dat
[12:19:50.995] INFO: clk: 4
[12:19:50.995] INFO: ctr: 4
[12:19:50.995] INFO: sda: 19
[12:19:50.995] INFO: tin: 9
[12:19:50.995] INFO: level: 15
[12:19:50.995] INFO: triggerdelay: 0
[12:19:50.995] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:19:50.995] INFO: Log level: DEBUG
[12:19:51.007] QUIET: Connection to board DTB_WRPRHI opened.
[12:19:51.010] 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:
------------------------------------------------------
[12:19:51.012] INFO: RPC call hashes of host and DTB match: 398089610
[12:19:52.615] INFO: DUT info:
[12:19:52.615] INFO: The DUT currently contains the following objects:
[12:19:52.615] INFO: 2 TBM Cores tbm08c (2 ON)
[12:19:52.615] INFO: TBM Core alpha (0): 7 registers set
[12:19:52.615] INFO: TBM Core beta (1): 7 registers set
[12:19:52.616] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:19:52.616] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:19:52.616] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:19:52.616] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:19:52.616] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:19:52.616] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:19:52.616] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:19:52.616] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:19:52.617] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:19:52.618] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:19:52.619] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:19:52.622] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30339072
[12:19:52.622] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x22a20e0
[12:19:52.622] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2044370
[12:19:52.622] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f6225d94010
[12:19:52.622] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f622bfff510
[12:19:52.622] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30347264 fPxarMemory = 0x7f6225d94010
[12:19:52.623] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 380.2mA
[12:19:52.624] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 464.7mA
[12:19:52.624] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[12:19:53.120] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2690f40
[12:19:53.187] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:19:53.187] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:19:53.187] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:19:53.193] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:19:53.193] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:19:53.193] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:19:53.205] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:19:53.205] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:19:53.222] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:19:53.222] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:19:53.248] INFO: PixTestCmd::init()
[12:19:53.256] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:19:53.256] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:19:53.256] INFO: readGainPedestalParameters data/mq616/phCalibrationFitErr35_C0.dat .. data/mq616/phCalibrationFitErr35_C15.dat
[12:19:53.506] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:19:53.506] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:19:53.520] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:19:53.527] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:19:53.527] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:19:53.527] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:19:53.551] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:19:53.551] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:19:53.551] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:19:53.551] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:19:53.551] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:19:53.558] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:19:53.558] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:19:53.579] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:19:53.579] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:19:53.579] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:19:53.579] INFO: readReadbackCal: data/mq616/readbackCal_C0.dat .. data/mq616/readbackCal_C15.dat
[12:19:53.589] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:19:53.597] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:19:53.597] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:19:53.597] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:19:53.597] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:19:53.618] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:19:53.619] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:20:06.857] INFO: ######################################################################
[12:20:06.857] INFO: PixTestAlive::doTest()
[12:20:06.857] INFO: ######################################################################
[12:20:06.861] INFO: ----------------------------------------------------------------------
[12:20:06.861] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:20:06.861] INFO: ----------------------------------------------------------------------
[12:20:06.863] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:20:07.243] INFO: Expecting 41600 events.
[12:20:11.652] INFO: 41600 events read in total (3691ms).
[12:20:11.800] INFO: Test took 4937ms.
[12:20:11.811] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:20:11.811] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[12:20:11.811] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[12:20:12.084] INFO: PixTestAlive::aliveTest() done
[12:20:12.084] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0
[12:20:12.084] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 46 31 59 60 72 65 82 56 56 76 62 66 72 45 41 42
[12:20:12.111] INFO: ----------------------------------------------------------------------
[12:20:12.111] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:20:12.111] INFO: ----------------------------------------------------------------------
[12:20:12.113] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:20:12.487] INFO: Expecting 41600 events.
[12:20:15.609] INFO: 41600 events read in total (2406ms).
[12:20:15.610] INFO: Test took 3497ms.
[12:20:15.610] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:20:15.610] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[12:20:15.610] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[12:20:15.610] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:20:16.033] INFO: PixTestAlive::maskTest() done
[12:20:16.033] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:20:16.054] INFO: ----------------------------------------------------------------------
[12:20:16.054] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:20:16.054] INFO: ----------------------------------------------------------------------
[12:20:16.056] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:20:16.434] INFO: Expecting 41600 events.
[12:20:20.829] INFO: 41600 events read in total (3680ms).
[12:20:20.831] INFO: Test took 4775ms.
[12:20:20.842] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:20:20.842] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[12:20:20.842] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[12:20:21.212] INFO: PixTestAlive::addressDecodingTest() done
[12:20:21.212] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:20:21.212] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[12:20:44.246] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:20:44.246] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C1_V0 -> PixelAlive_mod
[12:20:51.694] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[12:20:51.694] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[12:20:51.694] INFO: ----------------------------------------------------------------------
[12:20:51.694] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:20:51.694] INFO: ----------------------------------------------------------------------
[12:20:51.833] INFO: Expecting 768 events.
[12:20:52.967] INFO: 768 events read in total (419ms).
[12:20:52.967] INFO: Test took 1267ms.
[12:20:52.973] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:20:53.770] INFO: Expecting 41600 events.
[12:20:57.480] INFO: 41600 events read in total (3183ms).
[12:20:57.485] INFO: Test took 4512ms.
[12:20:57.622] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:20:57.622] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 792299
[12:20:57.622] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[12:20:57.623] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:20:57.681] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:20:58.347] INFO: Expecting 41600 events.
[12:21:02.208] INFO: 41600 events read in total (3334ms).
[12:21:02.213] INFO: Test took 4532ms.
[12:21:02.347] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:02.347] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 801869
[12:21:02.347] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[12:21:02.347] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:02.409] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:03.074] INFO: Expecting 41600 events.
[12:21:06.955] INFO: 41600 events read in total (3354ms).
[12:21:06.960] INFO: Test took 4551ms.
[12:21:07.095] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:07.095] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 804154
[12:21:07.095] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[12:21:07.095] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:07.156] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:07.821] INFO: Expecting 41600 events.
[12:21:11.711] INFO: 41600 events read in total (3364ms).
[12:21:11.716] INFO: Test took 4560ms.
[12:21:11.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:11.850] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 805822
[12:21:11.850] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[12:21:11.850] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:11.911] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:12.579] INFO: Expecting 41600 events.
[12:21:16.474] INFO: 41600 events read in total (3368ms).
[12:21:16.479] INFO: Test took 4568ms.
[12:21:16.611] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:16.611] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 804712
[12:21:16.611] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[12:21:16.611] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:16.674] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:17.340] INFO: Expecting 41600 events.
[12:21:21.254] INFO: 41600 events read in total (3387ms).
[12:21:21.259] INFO: Test took 4585ms.
[12:21:21.395] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:21.395] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 808166
[12:21:21.395] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[12:21:21.395] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:21.458] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:22.125] INFO: Expecting 41600 events.
[12:21:26.042] INFO: 41600 events read in total (3390ms).
[12:21:26.047] INFO: Test took 4589ms.
[12:21:26.181] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:26.181] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 805279
[12:21:26.181] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[12:21:26.181] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:26.244] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:26.911] INFO: Expecting 41600 events.
[12:21:30.838] INFO: 41600 events read in total (3400ms).
[12:21:30.843] INFO: Test took 4599ms.
[12:21:30.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:30.979] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 804512
[12:21:30.979] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[12:21:30.979] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:31.042] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:31.709] INFO: Expecting 41600 events.
[12:21:35.637] INFO: 41600 events read in total (3402ms).
[12:21:35.642] INFO: Test took 4600ms.
[12:21:35.780] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:35.780] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 805839
[12:21:35.780] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[12:21:35.781] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:35.841] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:36.506] INFO: Expecting 41600 events.
[12:21:40.429] INFO: 41600 events read in total (3396ms).
[12:21:40.434] INFO: Test took 4593ms.
[12:21:40.566] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:40.566] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 804107
[12:21:40.566] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[12:21:40.566] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:40.629] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:41.294] INFO: Expecting 41600 events.
[12:21:45.220] INFO: 41600 events read in total (3399ms).
[12:21:45.225] INFO: Test took 4596ms.
[12:21:45.358] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:45.358] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 806065
[12:21:45.358] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[12:21:45.359] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:45.420] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:46.085] INFO: Expecting 41600 events.
[12:21:50.006] INFO: 41600 events read in total (3394ms).
[12:21:50.012] INFO: Test took 4592ms.
[12:21:50.146] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:50.146] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 805434
[12:21:50.146] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[12:21:50.146] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:50.208] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:50.873] INFO: Expecting 41600 events.
[12:21:54.799] INFO: 41600 events read in total (3399ms).
[12:21:54.804] INFO: Test took 4596ms.
[12:21:54.937] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:54.938] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 806475
[12:21:54.938] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[12:21:54.938] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:55.002] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:55.668] INFO: Expecting 41600 events.
[12:21:59.560] INFO: 41600 events read in total (3365ms).
[12:21:59.565] INFO: Test took 4563ms.
[12:21:59.698] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:59.698] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 805500
[12:21:59.698] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[12:21:59.698] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:59.762] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:00.429] INFO: Expecting 41600 events.
[12:22:04.352] INFO: 41600 events read in total (3396ms).
[12:22:04.357] INFO: Test took 4595ms.
[12:22:04.488] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:04.488] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 805792
[12:22:04.488] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[12:22:04.489] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:04.552] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:05.216] INFO: Expecting 41600 events.
[12:22:09.143] INFO: 41600 events read in total (3400ms).
[12:22:09.148] INFO: Test took 4596ms.
[12:22:09.281] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:09.281] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 806428
[12:22:09.281] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[12:22:09.282] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:09.344] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:10.009] INFO: Expecting 41600 events.
[12:22:13.932] INFO: 41600 events read in total (3396ms).
[12:22:13.937] INFO: Test took 4593ms.
[12:22:14.070] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:14.070] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 806204
[12:22:14.070] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[12:22:14.070] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:14.132] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:14.797] INFO: Expecting 41600 events.
[12:22:18.723] INFO: 41600 events read in total (3399ms).
[12:22:18.728] INFO: Test took 4596ms.
[12:22:18.866] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:18.866] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 805300
[12:22:18.866] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[12:22:18.866] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:18.930] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:19.597] INFO: Expecting 41600 events.
[12:22:23.499] INFO: 41600 events read in total (3376ms).
[12:22:23.504] INFO: Test took 4574ms.
[12:22:23.636] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:23.636] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 806777
[12:22:23.636] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[12:22:23.636] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:23.698] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:24.368] INFO: Expecting 41600 events.
[12:22:28.108] INFO: 41600 events read in total (3213ms).
[12:22:28.113] INFO: Test took 4415ms.
[12:22:28.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:28.244] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 799028
[12:22:28.244] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[12:22:28.245] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:28.527] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 163 eff = 0.998534
[12:22:28.527] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 144 eff = 0.999303
[12:22:28.528] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 163 eff = 0.997764
[12:22:28.528] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 158 eff = 0.996514
[12:22:28.528] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 188 eff = 0.996418
[12:22:28.528] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 143 eff = 0.996202
[12:22:28.528] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 146 eff = 0.996298
[12:22:28.528] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 158 eff = 0.997115
[12:22:28.528] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 164 eff = 0.997356
[12:22:28.529] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 162 eff = 0.995096
[12:22:28.529] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 158 eff = 0.995385
[12:22:28.529] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 147 eff = 0.996274
[12:22:28.529] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 150 eff = 0.995793
[12:22:28.529] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 166 eff = 0.997668
[12:22:28.529] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 115 eff = 0.999207
[12:22:28.529] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 136 eff = 0.998822
[12:22:49.334] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[12:22:49.334] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[12:22:49.334] INFO: ----------------------------------------------------------------------
[12:22:49.334] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:22:49.334] INFO: ----------------------------------------------------------------------
[12:22:49.335] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[12:22:49.335] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[12:22:49.335] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[12:22:49.335] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[12:22:49.335] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[12:22:49.335] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[12:22:49.335] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[12:22:49.335] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[12:22:49.335] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[12:22:49.335] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[12:22:49.335] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[12:22:49.335] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[12:22:49.352] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:50.023] INFO: Expecting 208000 events.
[12:23:06.229] INFO: 208000 events read in total (15680ms).
[12:23:06.249] INFO: Test took 16897ms.
[12:23:06.984] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:06.984] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3721481
[12:23:06.984] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[12:23:06.984] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:23:07.463] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0
[12:23:07.463] INFO: number of red-efficiency pixels: 289 172 406 697 700 748 639 465 501 851 795 670 714 462 184 260
[12:23:07.463] INFO: number of X-ray hits detected: 171785 113580 190684 276004 293136 287974 278408 203050 197429 311360 307657 274817 286393 197174 117151 148320
[12:23:07.463] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:23:07.463] INFO: number of Vcal hits detected: 207682 207821 207541 207151 207205 207130 207203 207489 207416 206992 207085 207238 207121 207466 207802 207725
[12:23:07.463] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.6 99.7 99.6 99.7 99.8 99.7 99.6 99.6 99.7 99.6 99.8 99.9 99.9
[12:23:07.463] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.6 99.6 99.6 99.6 99.8 99.7 99.5 99.6 99.6 99.6 99.7 99.9 99.9
[12:23:07.463] INFO: X-ray hit rate [MHz/cm2]: 50.4 33.3 55.9 80.9 85.9 84.4 81.6 59.5 57.9 91.3 90.2 80.6 83.9 57.8 34.3 43.5
[12:23:07.463] INFO: PixTestHighRate::doXPixelAlive() done
[12:23:07.509] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[12:23:07.509] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[12:23:07.509] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[12:23:07.509] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[12:23:07.509] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[12:23:07.509] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[12:23:07.510] INFO: PixTest:: pg_setup set to default.
[12:23:22.110] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:23:22.110] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C14_V0 -> highRate_xraymap_mod
[12:23:35.246] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:23:35.247] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.9mA
[12:23:35.248] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 465.5mA
[12:23:35.248] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[12:23:35.248] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:23:35.248] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:23:35.248] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:23:35.248] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:23:35.248] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:23:35.248] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:23:35.248] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:23:35.248] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:23:35.248] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:23:35.248] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:23:35.341] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:23:35.341] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:23:35.354] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:23:35.354] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:23:35.354] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:23:35.354] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:23:35.355] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:23:35.355] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:23:35.357] QUIET: Connection to board 58 closed.
[12:23:35.436] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries