[13:45:58.033] INFO: *** Welcome to pxar ***
[13:45:58.033] INFO: *** Today: 2016/09/01
[13:45:58.058] INFO: *** Version: v1.9.0-818-g96727
[13:45:58.058] INFO: readRocDacs: data/mi434/dacParameters35_C0.dat .. data/mi434/dacParameters35_C15.dat
[13:45:58.059] INFO: readTbmDacs: data/mi434/tbmParameters_C0a.dat .. data/mi434/tbmParameters_C0b.dat
[13:45:58.059] INFO: readMaskFile: data/mi434/defaultMaskFile.dat
[13:45:58.059] INFO: readTrimFile: data/mi434/trimParameters35_C0.dat .. data/mi434/trimParameters35_C15.dat
[13:45:58.208] INFO: clk: 4
[13:45:58.208] INFO: ctr: 4
[13:45:58.208] INFO: sda: 19
[13:45:58.208] INFO: tin: 9
[13:45:58.208] INFO: level: 15
[13:45:58.208] INFO: triggerdelay: 0
[13:45:58.208] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[13:45:58.208] INFO: Log level: DEBUG
[13:45:58.220] QUIET: Connection to board DTB_WRPRHI opened.
[13:45:58.223] 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:
------------------------------------------------------
[13:45:58.226] INFO: RPC call hashes of host and DTB match: 398089610
[13:45:59.828] INFO: DUT info:
[13:45:59.828] INFO: The DUT currently contains the following objects:
[13:45:59.828] INFO: 2 TBM Cores tbm08c (2 ON)
[13:45:59.828] INFO: TBM Core alpha (0): 7 registers set
[13:45:59.828] INFO: TBM Core beta (1): 7 registers set
[13:45:59.828] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:45:59.828] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.828] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.828] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.828] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.828] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.828] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.828] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.828] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.828] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.828] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.828] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.828] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.829] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.829] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.829] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.829] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[13:45:59.829] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:45:59.830] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[13:45:59.831] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:45:59.832] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:45:59.834] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30334976
[13:45:59.834] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x17dc0e0
[13:45:59.834] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x157e370
[13:45:59.834] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7ff759d94010
[13:45:59.834] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7ff75ffff510
[13:45:59.834] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30343168 fPxarMemory = 0x7ff759d94010
[13:45:59.836] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381mA
[13:45:59.837] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 471.1mA
[13:45:59.837] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[13:46:00.331] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1bcaf10
[13:46:00.398] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[13:46:00.398] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[13:46:00.398] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[13:46:00.404] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[13:46:00.404] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[13:46:00.404] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[13:46:00.415] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[13:46:00.415] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[13:46:00.433] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[13:46:00.433] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[13:46:00.452] INFO: PixTestCmd::init()
[13:46:00.472] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[13:46:00.472] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[13:46:00.472] INFO: readGainPedestalParameters data/mi434/phCalibrationFitErr35_C0.dat .. data/mi434/phCalibrationFitErr35_C15.dat
[13:46:00.720] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[13:46:00.720] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[13:46:00.735] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[13:46:00.742] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[13:46:00.742] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[13:46:00.742] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[13:46:00.766] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[13:46:00.766] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[13:46:00.766] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[13:46:00.766] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[13:46:00.766] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[13:46:00.773] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[13:46:00.773] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[13:46:00.793] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[13:46:00.793] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[13:46:00.793] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[13:46:00.793] INFO: readReadbackCal: data/mi434/readbackCal_C0.dat .. data/mi434/readbackCal_C15.dat
[13:46:00.803] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[13:46:00.812] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[13:46:00.813] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[13:46:00.813] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[13:46:00.813] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[13:46:00.831] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[13:46:00.831] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[13:46:06.451] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[13:46:51.408] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[13:46:51.408] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[13:46:51.408] INFO: ----------------------------------------------------------------------
[13:46:51.408] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[13:46:51.408] INFO: ----------------------------------------------------------------------
[13:46:52.383] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[13:47:04.984] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[13:47:04.988] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:47:32.472] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1241582 events.
[13:47:38.438] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1241582, pixels seen in all events: 5353385
[13:47:38.549] INFO: Resuming triggers.
[13:47:51.156] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[13:47:51.196] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:48:18.539] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1241986 events.
[13:48:24.505] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1241986, pixels seen in all events: 5347280
[13:48:24.681] INFO: Resuming triggers.
[13:48:37.287] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[13:48:37.323] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:49:04.702] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1241912 events.
[13:49:10.599] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1241912, pixels seen in all events: 5347922
[13:49:10.838] INFO: Resuming triggers.
[13:49:23.447] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[13:49:23.484] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:49:50.793] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1242172 events.
[13:49:56.687] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1242172, pixels seen in all events: 5345213
[13:49:56.930] INFO: Resuming triggers.
[13:50:06.584] INFO: data taking finished, elapsed time: 60 seconds.
[13:50:06.781] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:50:27.680] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 951056 events.
[13:50:32.189] DEBUG: <PixTestXray.cc/processData:L823> # events read: 951056, pixels seen in all events: 4089327
[13:50:32.279] INFO: PixTest:: pg_setup set to default.
[13:50:32.369] INFO: PixTestXray::doPhRun() done
[13:51:16.144] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[13:51:16.144] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = qMap_Ag_C14_V0 -> qMap_Ag_mod
[13:51:24.872] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[13:51:24.872] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hMap_Ag_C2_V0 -> hMap_Ag_mod
[13:51:28.224] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[13:51:28.225] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.1mA
[13:51:28.226] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 471.9mA
[13:51:28.226] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[13:51:28.226] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[13:51:28.226] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[13:51:28.226] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[13:51:28.226] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[13:51:28.226] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[13:51:28.226] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[13:51:28.226] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[13:51:28.226] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[13:51:28.226] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[13:51:28.226] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[13:51:28.226] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[13:51:28.226] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[13:51:28.227] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[13:51:28.227] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[13:51:28.227] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[13:51:28.227] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[13:51:28.227] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[13:51:28.227] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[13:51:28.542] QUIET: Connection to board 58 closed.
[13:51:28.621] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[17:20:49.433] INFO: *** Welcome to pxar ***
[17:20:49.433] INFO: *** Today: 2016/09/01
[17:20:49.460] INFO: *** Version: v1.9.0-818-g96727
[17:20:49.460] INFO: readRocDacs: data/mi434/dacParameters35_C0.dat .. data/mi434/dacParameters35_C15.dat
[17:20:49.461] INFO: readTbmDacs: data/mi434/tbmParameters_C0a.dat .. data/mi434/tbmParameters_C0b.dat
[17:20:49.461] INFO: readMaskFile: data/mi434/defaultMaskFile.dat
[17:20:49.461] INFO: readTrimFile: data/mi434/trimParameters35_C0.dat .. data/mi434/trimParameters35_C15.dat
[17:20:49.612] INFO: clk: 4
[17:20:49.612] INFO: ctr: 4
[17:20:49.612] INFO: sda: 19
[17:20:49.612] INFO: tin: 9
[17:20:49.612] INFO: level: 15
[17:20:49.612] INFO: triggerdelay: 0
[17:20:49.612] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[17:20:49.612] INFO: Log level: DEBUG
[17:20:49.623] QUIET: Connection to board DTB_WRPRHI opened.
[17:20:49.627] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[17:20:49.629] INFO: RPC call hashes of host and DTB match: 398089610
[17:20:51.233] INFO: DUT info:
[17:20:51.233] INFO: The DUT currently contains the following objects:
[17:20:51.233] INFO: 2 TBM Cores tbm08c (2 ON)
[17:20:51.233] INFO: TBM Core alpha (0): 7 registers set
[17:20:51.233] INFO: TBM Core beta (1): 7 registers set
[17:20:51.233] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:20:51.233] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.233] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.233] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.233] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.234] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.234] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.234] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.234] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.234] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.234] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.234] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.234] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.234] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.234] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.234] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.234] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:20:51.234] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:20:51.235] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:20:51.236] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:20:51.237] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:20:51.240] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30339072
[17:20:51.240] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x151c170
[17:20:51.240] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x14aa370
[17:20:51.240] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f9119d94010
[17:20:51.240] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f911ffff510
[17:20:51.240] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30347264 fPxarMemory = 0x7f9119d94010
[17:20:51.241] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381mA
[17:20:51.242] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 470.3mA
[17:20:51.242] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -1.5 C
[17:20:51.741] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1af7030
[17:20:51.806] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:20:51.806] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:20:51.807] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:20:51.813] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:20:51.813] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:20:51.813] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:20:51.825] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:20:51.825] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:20:51.846] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:20:51.846] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:20:51.857] INFO: PixTestCmd::init()
[17:20:51.865] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:20:51.865] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:20:51.865] INFO: readGainPedestalParameters data/mi434/phCalibrationFitErr35_C0.dat .. data/mi434/phCalibrationFitErr35_C15.dat
[17:20:52.112] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:20:52.112] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:20:52.128] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:20:52.136] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:20:52.136] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[17:20:52.136] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:20:52.161] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:20:52.161] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:20:52.161] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:20:52.161] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:20:52.161] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:20:52.168] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:20:52.168] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:20:52.189] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:20:52.189] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:20:52.189] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:20:52.189] INFO: readReadbackCal: data/mi434/readbackCal_C0.dat .. data/mi434/readbackCal_C15.dat
[17:20:52.199] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:20:52.211] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[17:20:52.211] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[17:20:52.211] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[17:20:52.211] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[17:20:52.225] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:20:52.225] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:21:42.427] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[17:21:53.595] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[17:21:53.595] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[17:21:53.595] INFO: ----------------------------------------------------------------------
[17:21:53.595] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[17:21:53.595] INFO: ----------------------------------------------------------------------
[17:21:54.570] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[17:22:02.275] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[17:22:02.279] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:22:27.521] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 759163 events.
[17:22:39.502] DEBUG: <PixTestXray.cc/processData:L823> # events read: 759163, pixels seen in all events: 11143677
[17:22:39.755] INFO: Resuming triggers.
[17:22:47.466] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[17:22:47.505] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:23:12.806] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 759606 events.
[17:23:24.785] DEBUG: <PixTestXray.cc/processData:L823> # events read: 759606, pixels seen in all events: 11137015
[17:23:24.989] INFO: Resuming triggers.
[17:23:32.703] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[17:23:32.766] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:23:57.889] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 759923 events.
[17:24:09.918] DEBUG: <PixTestXray.cc/processData:L823> # events read: 759923, pixels seen in all events: 11132221
[17:24:10.168] INFO: Resuming triggers.
[17:24:17.884] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[17:24:17.925] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:24:43.124] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 760182 events.
[17:24:55.106] DEBUG: <PixTestXray.cc/processData:L823> # events read: 760182, pixels seen in all events: 11130157
[17:24:55.301] INFO: Resuming triggers.
[17:25:03.017] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[17:25:03.093] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:25:28.356] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 760050 events.
[17:25:40.437] DEBUG: <PixTestXray.cc/processData:L823> # events read: 760050, pixels seen in all events: 11131995
[17:25:40.676] INFO: Resuming triggers.
[17:25:48.388] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[17:25:48.423] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:26:13.585] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 759739 events.
[17:26:25.747] DEBUG: <PixTestXray.cc/processData:L823> # events read: 759739, pixels seen in all events: 11135266
[17:26:25.984] INFO: Resuming triggers.
[17:26:33.699] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[17:26:33.734] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:26:58.969] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 760045 events.
[17:27:11.120] DEBUG: <PixTestXray.cc/processData:L823> # events read: 760045, pixels seen in all events: 11130873
[17:27:11.360] INFO: Resuming triggers.
[17:27:17.447] INFO: data taking finished, elapsed time: 60 seconds.
[17:27:17.643] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[17:27:37.546] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 599654 events.
[17:27:47.220] DEBUG: <PixTestXray.cc/processData:L823> # events read: 599654, pixels seen in all events: 8775832
[17:27:47.419] INFO: PixTest:: pg_setup set to default.
[17:27:47.461] INFO: PixTestXray::doPhRun() done
[17:28:43.763] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:28:43.764] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.1mA
[17:28:43.765] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 472.7mA
[17:28:43.765] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[17:28:43.765] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:28:43.765] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:28:43.765] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:28:43.765] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:28:43.765] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:28:43.766] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:28:43.766] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:28:43.766] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:28:43.766] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:28:43.766] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[17:28:43.766] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:28:43.766] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:28:43.766] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:28:43.766] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:28:43.766] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:28:43.766] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[17:28:43.766] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:28:43.766] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:28:44.074] QUIET: Connection to board 58 closed.
[17:28:44.154] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[13:38:33.463] INFO: *** Welcome to pxar ***
[13:38:33.463] INFO: *** Today: 2016/09/01
[13:38:33.488] INFO: *** Version: v1.9.0-818-g96727
[13:38:33.488] INFO: readRocDacs: data/mi434/dacParameters35_C0.dat .. data/mi434/dacParameters35_C15.dat
[13:38:33.489] INFO: readTbmDacs: data/mi434/tbmParameters_C0a.dat .. data/mi434/tbmParameters_C0b.dat
[13:38:33.489] INFO: readMaskFile: data/mi434/defaultMaskFile.dat
[13:38:33.489] INFO: readTrimFile: data/mi434/trimParameters35_C0.dat .. data/mi434/trimParameters35_C15.dat
[13:38:33.642] INFO: clk: 4
[13:38:33.642] INFO: ctr: 4
[13:38:33.642] INFO: sda: 19
[13:38:33.642] INFO: tin: 9
[13:38:33.642] INFO: level: 15
[13:38:33.642] INFO: triggerdelay: 0
[13:38:33.642] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[13:38:33.642] INFO: Log level: DEBUG
[13:38:33.653] QUIET: Connection to board DTB_WRPRHI opened.
[13:38:33.657] 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:
------------------------------------------------------
[13:38:33.659] INFO: RPC call hashes of host and DTB match: 398089610
[13:38:35.263] INFO: DUT info:
[13:38:35.263] INFO: The DUT currently contains the following objects:
[13:38:35.263] INFO: 2 TBM Cores tbm08c (2 ON)
[13:38:35.263] INFO: TBM Core alpha (0): 7 registers set
[13:38:35.263] INFO: TBM Core beta (1): 7 registers set
[13:38:35.263] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:38:35.263] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.263] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.263] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.263] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.263] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.263] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.263] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.263] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.263] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.263] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.263] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.264] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.264] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.264] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.264] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.264] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[13:38:35.264] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:38:35.265] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:38:35.266] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:38:35.267] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:38:35.269] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30339072
[13:38:35.269] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1e130e0
[13:38:35.269] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1bb5370
[13:38:35.269] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f7e45d94010
[13:38:35.269] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f7e4bfff510
[13:38:35.269] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30347264 fPxarMemory = 0x7f7e45d94010
[13:38:35.271] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381mA
[13:38:35.272] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 471.1mA
[13:38:35.272] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[13:38:35.769] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2201f60
[13:38:35.833] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[13:38:35.833] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[13:38:35.833] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[13:38:35.839] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[13:38:35.839] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[13:38:35.839] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[13:38:35.851] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[13:38:35.851] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[13:38:35.880] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[13:38:35.880] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[13:38:35.897] INFO: PixTestCmd::init()
[13:38:35.907] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[13:38:35.908] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[13:38:35.908] INFO: readGainPedestalParameters data/mi434/phCalibrationFitErr35_C0.dat .. data/mi434/phCalibrationFitErr35_C15.dat
[13:38:36.164] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[13:38:36.164] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[13:38:36.180] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[13:38:36.187] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[13:38:36.187] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[13:38:36.187] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[13:38:36.213] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[13:38:36.214] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[13:38:36.214] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[13:38:36.214] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[13:38:36.214] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[13:38:36.220] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[13:38:36.220] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[13:38:36.240] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[13:38:36.241] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[13:38:36.241] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[13:38:36.241] INFO: readReadbackCal: data/mi434/readbackCal_C0.dat .. data/mi434/readbackCal_C15.dat
[13:38:36.250] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[13:38:36.259] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[13:38:36.259] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[13:38:36.259] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[13:38:36.259] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[13:38:36.277] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[13:38:36.277] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[13:38:49.363] INFO: ######################################################################
[13:38:49.363] INFO: PixTestAlive::doTest()
[13:38:49.363] INFO: ######################################################################
[13:38:49.367] INFO: ----------------------------------------------------------------------
[13:38:49.367] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:38:49.367] INFO: ----------------------------------------------------------------------
[13:38:49.369] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:38:49.745] INFO: Expecting 41600 events.
[13:38:54.153] INFO: 41600 events read in total (3690ms).
[13:38:54.299] INFO: Test took 4930ms.
[13:38:54.309] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:54.309] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[13:38:54.309] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[13:38:54.587] INFO: PixTestAlive::aliveTest() done
[13:38:54.587] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:38:54.587] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 23 9 21 38 40 55 50 39 36 44 45 38 43 29 17 18
[13:38:54.612] INFO: ----------------------------------------------------------------------
[13:38:54.612] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:38:54.612] INFO: ----------------------------------------------------------------------
[13:38:54.614] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:38:54.996] INFO: Expecting 41600 events.
[13:38:58.113] INFO: 41600 events read in total (2402ms).
[13:38:58.113] INFO: Test took 3499ms.
[13:38:58.113] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:58.113] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[13:38:58.113] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[13:38:58.114] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:38:58.536] INFO: PixTestAlive::maskTest() done
[13:38:58.536] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:38:58.559] INFO: ----------------------------------------------------------------------
[13:38:58.559] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:38:58.559] INFO: ----------------------------------------------------------------------
[13:38:58.561] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:38:58.939] INFO: Expecting 41600 events.
[13:39:03.331] INFO: 41600 events read in total (3677ms).
[13:39:03.332] INFO: Test took 4771ms.
[13:39:03.343] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:03.343] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[13:39:03.343] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[13:39:03.714] INFO: PixTestAlive::addressDecodingTest() done
[13:39:03.714] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:39:03.714] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[13:39:30.576] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[13:39:30.576] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[13:39:30.576] INFO: ----------------------------------------------------------------------
[13:39:30.576] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:39:30.576] INFO: ----------------------------------------------------------------------
[13:39:30.715] INFO: Expecting 768 events.
[13:39:31.848] INFO: 768 events read in total (417ms).
[13:39:31.848] INFO: Test took 1266ms.
[13:39:31.854] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:39:32.652] INFO: Expecting 41600 events.
[13:39:36.024] INFO: 41600 events read in total (2845ms).
[13:39:36.028] INFO: Test took 4174ms.
[13:39:36.112] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:36.112] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 472454
[13:39:36.112] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[13:39:36.112] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:39:36.151] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:39:36.818] INFO: Expecting 41600 events.
[13:39:40.301] INFO: 41600 events read in total (2957ms).
[13:39:40.305] INFO: Test took 4154ms.
[13:39:40.385] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:40.385] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 478902
[13:39:40.385] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[13:39:40.385] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:39:40.425] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:39:41.092] INFO: Expecting 41600 events.
[13:39:44.588] INFO: 41600 events read in total (2970ms).
[13:39:44.592] INFO: Test took 4167ms.
[13:39:44.671] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:44.672] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 481028
[13:39:44.672] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[13:39:44.672] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:39:44.711] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:39:45.377] INFO: Expecting 41600 events.
[13:39:48.878] INFO: 41600 events read in total (2975ms).
[13:39:48.882] INFO: Test took 4170ms.
[13:39:48.962] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:48.962] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 480909
[13:39:48.962] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[13:39:48.963] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:39:49.003] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:39:49.670] INFO: Expecting 41600 events.
[13:39:53.172] INFO: 41600 events read in total (2975ms).
[13:39:53.176] INFO: Test took 4172ms.
[13:39:53.254] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:53.254] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 481409
[13:39:53.254] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[13:39:53.255] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:39:53.295] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:39:53.961] INFO: Expecting 41600 events.
[13:39:57.465] INFO: 41600 events read in total (2978ms).
[13:39:57.469] INFO: Test took 4173ms.
[13:39:57.548] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:57.548] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 480979
[13:39:57.548] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[13:39:57.549] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:39:57.588] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:39:58.254] INFO: Expecting 41600 events.
[13:40:01.768] INFO: 41600 events read in total (2987ms).
[13:40:01.771] INFO: Test took 4183ms.
[13:40:01.856] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:01.856] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 480921
[13:40:01.856] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[13:40:01.857] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:40:01.898] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:40:02.564] INFO: Expecting 41600 events.
[13:40:06.058] INFO: 41600 events read in total (2967ms).
[13:40:06.062] INFO: Test took 4164ms.
[13:40:06.142] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:06.142] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 481102
[13:40:06.142] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[13:40:06.143] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:40:06.184] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:40:06.850] INFO: Expecting 41600 events.
[13:40:10.368] INFO: 41600 events read in total (2991ms).
[13:40:10.371] INFO: Test took 4187ms.
[13:40:10.450] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:10.451] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 481085
[13:40:10.451] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[13:40:10.451] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:40:10.492] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:40:11.158] INFO: Expecting 41600 events.
[13:40:14.677] INFO: 41600 events read in total (2992ms).
[13:40:14.680] INFO: Test took 4188ms.
[13:40:14.760] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:14.760] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 480373
[13:40:14.760] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[13:40:14.761] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:40:14.800] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:40:15.465] INFO: Expecting 41600 events.
[13:40:18.980] INFO: 41600 events read in total (2988ms).
[13:40:18.984] INFO: Test took 4183ms.
[13:40:19.063] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:19.063] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 480594
[13:40:19.063] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[13:40:19.063] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:40:19.103] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:40:19.768] INFO: Expecting 41600 events.
[13:40:23.277] INFO: 41600 events read in total (2982ms).
[13:40:23.281] INFO: Test took 4178ms.
[13:40:23.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:23.360] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 481104
[13:40:23.360] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[13:40:23.360] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:40:23.400] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:40:24.065] INFO: Expecting 41600 events.
[13:40:27.579] INFO: 41600 events read in total (2987ms).
[13:40:27.583] INFO: Test took 4183ms.
[13:40:27.662] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:27.662] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 481862
[13:40:27.662] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[13:40:27.663] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:40:27.703] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:40:28.369] INFO: Expecting 41600 events.
[13:40:31.850] INFO: 41600 events read in total (2954ms).
[13:40:31.853] INFO: Test took 4150ms.
[13:40:31.934] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:31.934] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 479758
[13:40:31.934] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[13:40:31.935] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:40:31.975] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:40:32.641] INFO: Expecting 41600 events.
[13:40:36.159] INFO: 41600 events read in total (2991ms).
[13:40:36.163] INFO: Test took 4188ms.
[13:40:36.242] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:36.242] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 480603
[13:40:36.242] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[13:40:36.243] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:40:36.283] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:40:36.948] INFO: Expecting 41600 events.
[13:40:40.463] INFO: 41600 events read in total (2988ms).
[13:40:40.466] INFO: Test took 4183ms.
[13:40:40.545] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:40.545] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 479432
[13:40:40.545] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[13:40:40.545] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:40:40.585] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:40:41.250] INFO: Expecting 41600 events.
[13:40:44.772] INFO: 41600 events read in total (2995ms).
[13:40:44.776] INFO: Test took 4191ms.
[13:40:44.854] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:44.854] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 480805
[13:40:44.855] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[13:40:44.855] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:40:44.895] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:40:45.560] INFO: Expecting 41600 events.
[13:40:49.084] INFO: 41600 events read in total (2997ms).
[13:40:49.087] INFO: Test took 4192ms.
[13:40:49.168] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:49.168] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 480338
[13:40:49.168] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[13:40:49.168] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:40:49.208] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:40:49.875] INFO: Expecting 41600 events.
[13:40:53.322] INFO: 41600 events read in total (2921ms).
[13:40:53.326] INFO: Test took 4118ms.
[13:40:53.406] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:53.406] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 479568
[13:40:53.406] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[13:40:53.406] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:40:53.446] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:40:54.111] INFO: Expecting 41600 events.
[13:40:57.422] INFO: 41600 events read in total (2784ms).
[13:40:57.426] INFO: Test took 3980ms.
[13:40:57.504] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:57.504] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 472539
[13:40:57.504] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[13:40:57.504] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:40:57.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 163 eff = 0.999663
[13:40:57.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 144 eff = 0.999712
[13:40:57.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 163 eff = 0.999423
[13:40:57.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 193 eff = 0.999159
[13:40:57.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 160 eff = 0.99851
[13:40:57.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 158 eff = 0.998774
[13:40:57.766] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 151 eff = 0.999111
[13:40:57.766] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 180 eff = 0.999038
[13:40:57.766] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 139 eff = 0.999063
[13:40:57.766] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 166 eff = 0.99875
[13:40:57.766] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 180 eff = 0.99875
[13:40:57.766] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 156 eff = 0.998798
[13:40:57.766] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 165 eff = 0.99875
[13:40:57.767] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 140 eff = 0.999423
[13:40:57.767] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 155 eff = 0.999663
[13:40:57.767] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 154 eff = 0.999615
[13:41:38.664] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[13:41:38.664] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[13:41:38.664] INFO: ----------------------------------------------------------------------
[13:41:38.664] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:41:38.664] INFO: ----------------------------------------------------------------------
[13:41:38.664] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[13:41:38.664] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[13:41:38.664] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[13:41:38.664] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[13:41:38.664] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[13:41:38.664] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[13:41:38.664] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[13:41:38.665] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[13:41:38.665] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[13:41:38.665] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[13:41:38.665] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[13:41:38.665] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[13:41:38.683] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:41:39.351] INFO: Expecting 208000 events.
[13:41:52.841] INFO: 208000 events read in total (12963ms).
[13:41:52.854] INFO: Test took 14171ms.
[13:41:53.296] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:53.296] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2120463
[13:41:53.296] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[13:41:53.296] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:41:53.674] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:41:53.674] INFO: number of red-efficiency pixels: 136 73 181 214 294 292 252 208 231 275 249 255 247 166 80 99
[13:41:53.674] INFO: number of X-ray hits detected: 92957 61985 104980 154931 161624 159299 156249 145892 142976 163418 161794 154147 156030 103032 60022 74568
[13:41:53.674] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:41:53.674] INFO: number of Vcal hits detected: 207859 207926 207814 207779 207650 207688 207738 207782 207764 207713 207735 207737 207734 207824 207920 207896
[13:41:53.674] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[13:41:53.674] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[13:41:53.674] INFO: X-ray hit rate [MHz/cm2]: 27.2 18.2 30.8 45.4 47.4 46.7 45.8 42.8 41.9 47.9 47.4 45.2 45.7 30.2 17.6 21.9
[13:41:53.674] INFO: PixTestHighRate::doXPixelAlive() done
[13:41:53.718] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[13:41:53.718] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[13:41:53.718] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[13:41:53.718] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[13:41:53.718] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[13:41:53.718] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[13:41:53.719] INFO: PixTest:: pg_setup set to default.
[13:42:16.416] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[13:42:16.416] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C5_V0 -> highRate_xraymap_mod
[13:42:19.984] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[13:42:19.985] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.1mA
[13:42:19.985] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 471.1mA
[13:42:19.985] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[13:42:19.985] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[13:42:19.986] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[13:42:19.986] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[13:42:19.986] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[13:42:19.986] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[13:42:19.986] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[13:42:19.986] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[13:42:19.986] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[13:42:19.986] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[13:42:19.986] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[13:42:20.076] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[13:42:20.076] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[13:42:20.085] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[13:42:20.086] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[13:42:20.086] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[13:42:20.086] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[13:42:20.086] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[13:42:20.086] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[13:42:20.088] QUIET: Connection to board 58 closed.
[13:42:20.168] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[13:35:16.785] INFO: *** Welcome to pxar ***
[13:35:16.785] INFO: *** Today: 2016/09/01
[13:35:16.808] INFO: *** Version: v1.9.0-818-g96727
[13:35:16.808] INFO: readRocDacs: data/mi434/dacParameters35_C0.dat .. data/mi434/dacParameters35_C15.dat
[13:35:16.809] INFO: readTbmDacs: data/mi434/tbmParameters_C0a.dat .. data/mi434/tbmParameters_C0b.dat
[13:35:16.809] INFO: readMaskFile: data/mi434/defaultMaskFile.dat
[13:35:16.809] INFO: readTrimFile: data/mi434/trimParameters35_C0.dat .. data/mi434/trimParameters35_C15.dat
[13:35:16.962] INFO: clk: 4
[13:35:16.962] INFO: ctr: 4
[13:35:16.962] INFO: sda: 19
[13:35:16.962] INFO: tin: 9
[13:35:16.962] INFO: level: 15
[13:35:16.962] INFO: triggerdelay: 0
[13:35:16.962] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[13:35:16.962] INFO: Log level: DEBUG
[13:35:16.975] QUIET: Connection to board DTB_WRPRHI opened.
[13:35:16.978] 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:
------------------------------------------------------
[13:35:16.981] INFO: RPC call hashes of host and DTB match: 398089610
[13:35:18.584] INFO: DUT info:
[13:35:18.584] INFO: The DUT currently contains the following objects:
[13:35:18.584] INFO: 2 TBM Cores tbm08c (2 ON)
[13:35:18.584] INFO: TBM Core alpha (0): 7 registers set
[13:35:18.584] INFO: TBM Core beta (1): 7 registers set
[13:35:18.584] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:35:18.584] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:35:18.585] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[13:35:18.585] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:35:18.585] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:35:18.585] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:35:18.585] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[13:35:18.585] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[13:35:18.585] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:35:18.585] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[13:35:18.585] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[13:35:18.585] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[13:35:18.586] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[13:35:18.587] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:35:18.588] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:35:18.591] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30330880
[13:35:18.591] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1d4b0e0
[13:35:18.591] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1aed370
[13:35:18.591] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fb395d94010
[13:35:18.591] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fb39bfff510
[13:35:18.591] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30339072 fPxarMemory = 0x7fb395d94010
[13:35:18.592] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381mA
[13:35:18.593] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 470.3mA
[13:35:18.593] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[13:35:19.092] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2139f70
[13:35:19.154] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[13:35:19.154] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[13:35:19.154] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[13:35:19.160] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[13:35:19.160] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[13:35:19.160] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[13:35:19.171] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[13:35:19.171] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[13:35:19.178] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[13:35:19.179] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[13:35:19.198] INFO: PixTestCmd::init()
[13:35:19.205] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[13:35:19.205] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[13:35:19.205] INFO: readGainPedestalParameters data/mi434/phCalibrationFitErr35_C0.dat .. data/mi434/phCalibrationFitErr35_C15.dat
[13:35:19.454] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[13:35:19.454] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[13:35:19.468] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[13:35:19.474] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[13:35:19.474] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[13:35:19.474] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[13:35:19.495] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[13:35:19.495] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[13:35:19.495] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[13:35:19.495] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[13:35:19.495] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[13:35:19.501] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[13:35:19.501] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[13:35:19.515] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[13:35:19.515] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[13:35:19.515] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[13:35:19.515] INFO: readReadbackCal: data/mi434/readbackCal_C0.dat .. data/mi434/readbackCal_C15.dat
[13:35:19.527] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[13:35:19.534] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[13:35:19.535] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[13:35:19.535] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[13:35:19.535] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[13:35:19.547] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[13:35:19.548] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[13:35:30.659] INFO: ######################################################################
[13:35:30.659] INFO: PixTestAlive::doTest()
[13:35:30.659] INFO: ######################################################################
[13:35:30.663] INFO: ----------------------------------------------------------------------
[13:35:30.663] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:35:30.663] INFO: ----------------------------------------------------------------------
[13:35:30.665] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:35:31.044] INFO: Expecting 41600 events.
[13:35:35.449] INFO: 41600 events read in total (3686ms).
[13:35:35.595] INFO: Test took 4930ms.
[13:35:35.606] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:35.606] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[13:35:35.606] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[13:35:35.881] INFO: PixTestAlive::aliveTest() done
[13:35:35.881] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:35:35.881] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 24 28 42 49 63 68 64 51 53 62 56 54 66 44 25 27
[13:35:35.909] INFO: ----------------------------------------------------------------------
[13:35:35.909] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:35:35.909] INFO: ----------------------------------------------------------------------
[13:35:35.912] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:35:36.290] INFO: Expecting 41600 events.
[13:35:39.407] INFO: 41600 events read in total (2401ms).
[13:35:39.407] INFO: Test took 3495ms.
[13:35:39.408] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:39.408] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[13:35:39.408] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[13:35:39.408] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:35:39.830] INFO: PixTestAlive::maskTest() done
[13:35:39.830] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:35:39.853] INFO: ----------------------------------------------------------------------
[13:35:39.853] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:35:39.853] INFO: ----------------------------------------------------------------------
[13:35:39.855] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:35:40.234] INFO: Expecting 41600 events.
[13:35:44.610] INFO: 41600 events read in total (3661ms).
[13:35:44.611] INFO: Test took 4756ms.
[13:35:44.622] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:44.622] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[13:35:44.622] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[13:35:44.993] INFO: PixTestAlive::addressDecodingTest() done
[13:35:44.993] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:35:44.994] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[13:35:58.184] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[13:35:58.184] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[13:35:58.184] INFO: ----------------------------------------------------------------------
[13:35:58.184] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:35:58.184] INFO: ----------------------------------------------------------------------
[13:35:58.323] INFO: Expecting 768 events.
[13:35:59.457] INFO: 768 events read in total (419ms).
[13:35:59.457] INFO: Test took 1268ms.
[13:35:59.463] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:36:00.260] INFO: Expecting 41600 events.
[13:36:03.891] INFO: 41600 events read in total (3104ms).
[13:36:03.896] INFO: Test took 4433ms.
[13:36:04.018] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:04.018] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684734
[13:36:04.018] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[13:36:04.019] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:36:04.072] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:36:04.738] INFO: Expecting 41600 events.
[13:36:08.481] INFO: 41600 events read in total (3216ms).
[13:36:08.486] INFO: Test took 4414ms.
[13:36:08.599] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:08.599] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 691381
[13:36:08.599] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[13:36:08.599] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:36:08.654] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:36:09.319] INFO: Expecting 41600 events.
[13:36:13.072] INFO: 41600 events read in total (3226ms).
[13:36:13.076] INFO: Test took 4422ms.
[13:36:13.194] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:13.194] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 691922
[13:36:13.194] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[13:36:13.195] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:36:13.249] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:36:13.915] INFO: Expecting 41600 events.
[13:36:17.659] INFO: 41600 events read in total (3217ms).
[13:36:17.663] INFO: Test took 4414ms.
[13:36:17.781] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:17.781] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 691507
[13:36:17.781] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[13:36:17.781] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:36:17.837] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:36:18.503] INFO: Expecting 41600 events.
[13:36:22.265] INFO: 41600 events read in total (3235ms).
[13:36:22.270] INFO: Test took 4433ms.
[13:36:22.386] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:22.386] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 691920
[13:36:22.386] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[13:36:22.387] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:36:22.441] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:36:23.108] INFO: Expecting 41600 events.
[13:36:26.871] INFO: 41600 events read in total (3237ms).
[13:36:26.876] INFO: Test took 4435ms.
[13:36:26.992] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:26.992] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 690886
[13:36:26.992] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[13:36:26.992] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:36:27.047] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:36:27.712] INFO: Expecting 41600 events.
[13:36:31.504] INFO: 41600 events read in total (3265ms).
[13:36:31.508] INFO: Test took 4461ms.
[13:36:31.626] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:31.626] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 691482
[13:36:31.626] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[13:36:31.626] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:36:31.681] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:36:32.349] INFO: Expecting 41600 events.
[13:36:36.131] INFO: 41600 events read in total (3255ms).
[13:36:36.136] INFO: Test took 4455ms.
[13:36:36.253] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:36.253] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692463
[13:36:36.253] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[13:36:36.253] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:36:36.309] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:36:36.976] INFO: Expecting 41600 events.
[13:36:40.757] INFO: 41600 events read in total (3254ms).
[13:36:40.762] INFO: Test took 4453ms.
[13:36:40.878] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:40.878] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 693382
[13:36:40.878] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[13:36:40.878] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:36:40.933] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:36:41.598] INFO: Expecting 41600 events.
[13:36:45.393] INFO: 41600 events read in total (3268ms).
[13:36:45.398] INFO: Test took 4465ms.
[13:36:45.514] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:45.514] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692284
[13:36:45.514] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[13:36:45.514] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:36:45.569] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:36:46.234] INFO: Expecting 41600 events.
[13:36:50.020] INFO: 41600 events read in total (3259ms).
[13:36:50.025] INFO: Test took 4456ms.
[13:36:50.143] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:50.143] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692544
[13:36:50.143] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[13:36:50.143] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:36:50.197] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:36:50.865] INFO: Expecting 41600 events.
[13:36:54.643] INFO: 41600 events read in total (3251ms).
[13:36:54.648] INFO: Test took 4451ms.
[13:36:54.764] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:54.764] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692756
[13:36:54.764] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[13:36:54.765] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:36:54.819] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:36:55.486] INFO: Expecting 41600 events.
[13:36:59.271] INFO: 41600 events read in total (3259ms).
[13:36:59.276] INFO: Test took 4457ms.
[13:36:59.393] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:59.393] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692448
[13:36:59.393] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[13:36:59.394] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:36:59.448] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:37:00.114] INFO: Expecting 41600 events.
[13:37:03.899] INFO: 41600 events read in total (3259ms).
[13:37:03.903] INFO: Test took 4455ms.
[13:37:04.019] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:04.019] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692064
[13:37:04.019] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[13:37:04.020] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:37:04.074] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:37:04.740] INFO: Expecting 41600 events.
[13:37:08.527] INFO: 41600 events read in total (3260ms).
[13:37:08.531] INFO: Test took 4457ms.
[13:37:08.648] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:08.648] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692140
[13:37:08.648] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[13:37:08.649] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:37:08.705] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:37:09.371] INFO: Expecting 41600 events.
[13:37:13.169] INFO: 41600 events read in total (3258ms).
[13:37:13.173] INFO: Test took 4468ms.
[13:37:13.290] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:13.291] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692382
[13:37:13.291] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[13:37:13.291] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:37:13.347] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:37:14.013] INFO: Expecting 41600 events.
[13:37:17.740] INFO: 41600 events read in total (3200ms).
[13:37:17.745] INFO: Test took 4398ms.
[13:37:17.864] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:17.864] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692221
[13:37:17.864] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[13:37:17.864] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:37:17.920] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:37:18.586] INFO: Expecting 41600 events.
[13:37:22.373] INFO: 41600 events read in total (3260ms).
[13:37:22.377] INFO: Test took 4457ms.
[13:37:22.492] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:22.492] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692438
[13:37:22.492] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[13:37:22.492] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:37:22.546] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:37:23.211] INFO: Expecting 41600 events.
[13:37:26.981] INFO: 41600 events read in total (3243ms).
[13:37:26.985] INFO: Test took 4439ms.
[13:37:27.104] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:27.104] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 691590
[13:37:27.104] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[13:37:27.105] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:37:27.160] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:37:27.827] INFO: Expecting 41600 events.
[13:37:31.362] INFO: 41600 events read in total (3009ms).
[13:37:31.367] INFO: Test took 4207ms.
[13:37:31.480] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:31.480] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 682280
[13:37:31.480] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[13:37:31.480] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:37:31.756] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 142 eff = 0.999207
[13:37:31.756] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 157 eff = 0.999639
[13:37:31.756] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 183 eff = 0.998726
[13:37:31.756] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 193 eff = 0.997572
[13:37:31.756] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 169 eff = 0.997452
[13:37:31.756] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 164 eff = 0.997692
[13:37:31.757] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 150 eff = 0.997668
[13:37:31.757] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 179 eff = 0.997909
[13:37:31.757] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 142 eff = 0.997716
[13:37:31.757] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 168 eff = 0.997524
[13:37:31.757] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 183 eff = 0.99774
[13:37:31.757] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 159 eff = 0.99774
[13:37:31.757] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 164 eff = 0.997644
[13:37:31.758] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 164 eff = 0.998582
[13:37:31.758] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 157 eff = 0.999567
[13:37:31.758] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 168 eff = 0.999471
[13:37:39.056] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[13:37:39.056] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[13:37:39.056] INFO: ----------------------------------------------------------------------
[13:37:39.056] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:37:39.056] INFO: ----------------------------------------------------------------------
[13:37:39.056] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[13:37:39.056] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[13:37:39.056] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[13:37:39.056] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[13:37:39.056] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[13:37:39.056] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[13:37:39.056] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[13:37:39.056] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[13:37:39.056] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[13:37:39.056] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[13:37:39.056] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[13:37:39.057] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[13:37:39.074] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:37:39.745] INFO: Expecting 208000 events.
[13:37:54.938] INFO: 208000 events read in total (14666ms).
[13:37:54.955] INFO: Test took 15881ms.
[13:37:55.577] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:55.577] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3169578
[13:37:55.577] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[13:37:55.578] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:37:56.023] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:37:56.023] INFO: number of red-efficiency pixels: 274 131 310 429 497 487 488 464 422 484 489 493 492 273 124 136
[13:37:56.023] INFO: number of X-ray hits detected: 141184 92682 158144 234121 243693 240000 236570 220184 215344 247556 245213 232079 235964 156487 90271 113527
[13:37:56.023] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:37:56.024] INFO: number of Vcal hits detected: 207716 207864 207666 207544 207385 207466 207476 207487 207550 207465 207460 207458 207460 207709 207863 207860
[13:37:56.024] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[13:37:56.024] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.7 99.8 99.8 99.7 99.7 99.7 99.7 99.9 99.9 99.9
[13:37:56.024] INFO: X-ray hit rate [MHz/cm2]: 41.4 27.2 46.4 68.6 71.4 70.3 69.3 64.5 63.1 72.6 71.9 68.0 69.2 45.9 26.5 33.3
[13:37:56.024] INFO: PixTestHighRate::doXPixelAlive() done
[13:37:56.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[13:37:56.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[13:37:56.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[13:37:56.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[13:37:56.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[13:37:56.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[13:37:56.070] INFO: PixTest:: pg_setup set to default.
[13:38:19.848] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[13:38:19.848] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C8_V0 -> highRate_xraymap_mod
[13:38:24.904] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[13:38:24.905] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.1mA
[13:38:24.906] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 471.1mA
[13:38:24.906] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[13:38:24.906] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[13:38:24.906] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[13:38:24.906] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[13:38:24.906] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[13:38:24.906] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[13:38:24.906] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[13:38:24.906] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[13:38:24.906] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[13:38:24.906] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[13:38:24.906] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[13:38:24.996] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[13:38:24.996] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[13:38:25.005] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[13:38:25.005] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[13:38:25.006] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[13:38:25.006] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[13:38:25.006] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[13:38:25.006] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[13:38:25.008] QUIET: Connection to board 58 closed.
[13:38:25.087] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[13:31:44.811] INFO: *** Welcome to pxar ***
[13:31:44.811] INFO: *** Today: 2016/09/01
[13:31:44.836] INFO: *** Version: v1.9.0-818-g96727
[13:31:44.836] INFO: readRocDacs: data/mi434/dacParameters35_C0.dat .. data/mi434/dacParameters35_C15.dat
[13:31:44.837] INFO: readTbmDacs: data/mi434/tbmParameters_C0a.dat .. data/mi434/tbmParameters_C0b.dat
[13:31:44.837] INFO: readMaskFile: data/mi434/defaultMaskFile.dat
[13:31:44.837] INFO: readTrimFile: data/mi434/trimParameters35_C0.dat .. data/mi434/trimParameters35_C15.dat
[13:31:44.988] INFO: clk: 4
[13:31:44.988] INFO: ctr: 4
[13:31:44.988] INFO: sda: 19
[13:31:44.988] INFO: tin: 9
[13:31:44.988] INFO: level: 15
[13:31:44.988] INFO: triggerdelay: 0
[13:31:44.988] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[13:31:44.988] INFO: Log level: DEBUG
[13:31:44.999] QUIET: Connection to board DTB_WRPRHI opened.
[13:31:45.002] 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:
------------------------------------------------------
[13:31:45.005] INFO: RPC call hashes of host and DTB match: 398089610
[13:31:46.608] INFO: DUT info:
[13:31:46.608] INFO: The DUT currently contains the following objects:
[13:31:46.608] INFO: 2 TBM Cores tbm08c (2 ON)
[13:31:46.608] INFO: TBM Core alpha (0): 7 registers set
[13:31:46.608] INFO: TBM Core beta (1): 7 registers set
[13:31:46.608] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:31:46.608] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.608] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.608] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.608] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.608] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.608] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.608] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.608] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.608] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.608] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.608] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.608] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.608] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.608] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.608] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.608] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[13:31:46.609] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[13:31:46.610] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[13:31:46.611] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:31:46.612] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:31:46.614] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30330880
[13:31:46.614] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x22610e0
[13:31:46.614] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2003370
[13:31:46.614] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f7049d94010
[13:31:46.614] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f704ffff510
[13:31:46.614] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30339072 fPxarMemory = 0x7f7049d94010
[13:31:46.615] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381mA
[13:31:46.617] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 471.1mA
[13:31:46.617] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[13:31:47.112] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x264ff10
[13:31:47.176] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[13:31:47.177] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[13:31:47.177] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[13:31:47.184] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[13:31:47.184] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[13:31:47.184] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[13:31:47.201] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[13:31:47.201] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[13:31:47.218] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[13:31:47.218] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[13:31:47.233] INFO: PixTestCmd::init()
[13:31:47.246] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[13:31:47.246] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[13:31:47.246] INFO: readGainPedestalParameters data/mi434/phCalibrationFitErr35_C0.dat .. data/mi434/phCalibrationFitErr35_C15.dat
[13:31:47.494] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[13:31:47.494] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[13:31:47.509] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[13:31:47.515] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[13:31:47.515] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[13:31:47.515] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[13:31:47.535] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[13:31:47.535] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[13:31:47.535] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[13:31:47.535] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[13:31:47.535] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[13:31:47.541] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[13:31:47.542] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[13:31:47.556] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[13:31:47.556] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[13:31:47.556] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[13:31:47.556] INFO: readReadbackCal: data/mi434/readbackCal_C0.dat .. data/mi434/readbackCal_C15.dat
[13:31:47.566] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[13:31:47.574] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[13:31:47.574] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[13:31:47.574] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[13:31:47.574] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[13:31:47.587] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[13:31:47.587] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[13:31:52.027] INFO: ######################################################################
[13:31:52.027] INFO: PixTestAlive::doTest()
[13:31:52.027] INFO: ######################################################################
[13:31:52.030] INFO: ----------------------------------------------------------------------
[13:31:52.030] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:31:52.030] INFO: ----------------------------------------------------------------------
[13:31:52.033] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:31:52.413] INFO: Expecting 41600 events.
[13:31:56.835] INFO: 41600 events read in total (3703ms).
[13:31:56.980] INFO: Test took 4947ms.
[13:31:56.990] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:31:56.990] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[13:31:56.990] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[13:31:57.269] INFO: PixTestAlive::aliveTest() done
[13:31:57.269] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:31:57.269] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 41 29 49 60 89 100 68 82 83 82 79 63 98 71 29 38
[13:31:57.293] INFO: ----------------------------------------------------------------------
[13:31:57.293] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:31:57.293] INFO: ----------------------------------------------------------------------
[13:31:57.295] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:31:57.673] INFO: Expecting 41600 events.
[13:32:00.785] INFO: 41600 events read in total (2397ms).
[13:32:00.786] INFO: Test took 3491ms.
[13:32:00.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:00.786] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[13:32:00.786] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[13:32:00.786] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:32:01.208] INFO: PixTestAlive::maskTest() done
[13:32:01.208] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:32:01.228] INFO: ----------------------------------------------------------------------
[13:32:01.228] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:32:01.228] INFO: ----------------------------------------------------------------------
[13:32:01.231] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:32:01.607] INFO: Expecting 41600 events.
[13:32:05.986] INFO: 41600 events read in total (3663ms).
[13:32:05.987] INFO: Test took 4756ms.
[13:32:05.999] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:05.999] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[13:32:05.999] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[13:32:06.368] INFO: PixTestAlive::addressDecodingTest() done
[13:32:06.368] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:32:06.368] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[13:32:21.000] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[13:32:21.000] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[13:32:21.000] INFO: ----------------------------------------------------------------------
[13:32:21.000] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:32:21.000] INFO: ----------------------------------------------------------------------
[13:32:21.139] INFO: Expecting 768 events.
[13:32:22.273] INFO: 768 events read in total (419ms).
[13:32:22.273] INFO: Test took 1267ms.
[13:32:22.279] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:32:23.078] INFO: Expecting 41600 events.
[13:32:26.995] INFO: 41600 events read in total (3391ms).
[13:32:27.000] INFO: Test took 4721ms.
[13:32:27.201] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:27.201] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 908698
[13:32:27.201] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[13:32:27.201] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:32:27.267] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:32:27.932] INFO: Expecting 41600 events.
[13:32:31.907] INFO: 41600 events read in total (3448ms).
[13:32:31.913] INFO: Test took 4646ms.
[13:32:32.061] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:32.061] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 915940
[13:32:32.062] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[13:32:32.062] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:32:32.131] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:32:32.796] INFO: Expecting 41600 events.
[13:32:36.822] INFO: 41600 events read in total (3499ms).
[13:32:36.827] INFO: Test took 4696ms.
[13:32:36.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:36.979] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 918171
[13:32:36.979] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[13:32:36.979] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:32:37.046] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:32:37.712] INFO: Expecting 41600 events.
[13:32:41.724] INFO: 41600 events read in total (3485ms).
[13:32:41.729] INFO: Test took 4683ms.
[13:32:41.879] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:41.879] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 915877
[13:32:41.879] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[13:32:41.879] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:32:41.948] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:32:42.615] INFO: Expecting 41600 events.
[13:32:46.645] INFO: 41600 events read in total (3504ms).
[13:32:46.651] INFO: Test took 4703ms.
[13:32:46.801] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:46.802] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 918026
[13:32:46.802] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[13:32:46.802] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:32:46.873] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:32:47.539] INFO: Expecting 41600 events.
[13:32:51.570] INFO: 41600 events read in total (3504ms).
[13:32:51.575] INFO: Test took 4703ms.
[13:32:51.723] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:51.723] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 918850
[13:32:51.724] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[13:32:51.724] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:32:51.793] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:32:52.459] INFO: Expecting 41600 events.
[13:32:56.513] INFO: 41600 events read in total (3527ms).
[13:32:56.519] INFO: Test took 4726ms.
[13:32:56.670] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:56.670] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 918449
[13:32:56.670] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[13:32:56.670] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:32:56.741] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:32:57.408] INFO: Expecting 41600 events.
[13:33:01.464] INFO: 41600 events read in total (3530ms).
[13:33:01.470] INFO: Test took 4729ms.
[13:33:01.619] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:01.619] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 920684
[13:33:01.619] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[13:33:01.620] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:33:01.689] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:33:02.357] INFO: Expecting 41600 events.
[13:33:06.402] INFO: 41600 events read in total (3518ms).
[13:33:06.408] INFO: Test took 4719ms.
[13:33:06.557] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:06.557] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 919914
[13:33:06.557] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[13:33:06.558] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:33:06.627] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:33:07.292] INFO: Expecting 41600 events.
[13:33:11.337] INFO: 41600 events read in total (3519ms).
[13:33:11.343] INFO: Test took 4716ms.
[13:33:11.492] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:11.492] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 918725
[13:33:11.492] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[13:33:11.493] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:33:11.562] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:33:12.227] INFO: Expecting 41600 events.
[13:33:16.241] INFO: 41600 events read in total (3487ms).
[13:33:16.246] INFO: Test took 4684ms.
[13:33:16.393] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:16.393] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 920327
[13:33:16.393] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[13:33:16.394] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:33:16.463] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:33:17.128] INFO: Expecting 41600 events.
[13:33:21.181] INFO: 41600 events read in total (3526ms).
[13:33:21.187] INFO: Test took 4724ms.
[13:33:21.338] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:21.338] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 919271
[13:33:21.338] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[13:33:21.338] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:33:21.408] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:33:22.073] INFO: Expecting 41600 events.
[13:33:26.132] INFO: 41600 events read in total (3533ms).
[13:33:26.137] INFO: Test took 4729ms.
[13:33:26.286] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:26.286] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 917938
[13:33:26.286] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[13:33:26.286] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:33:26.356] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:33:27.021] INFO: Expecting 41600 events.
[13:33:31.065] INFO: 41600 events read in total (3517ms).
[13:33:31.070] INFO: Test took 4714ms.
[13:33:31.217] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:31.217] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 917886
[13:33:31.217] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[13:33:31.218] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:33:31.288] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:33:31.953] INFO: Expecting 41600 events.
[13:33:36.011] INFO: 41600 events read in total (3531ms).
[13:33:36.016] INFO: Test took 4728ms.
[13:33:36.165] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:36.166] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 919627
[13:33:36.166] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[13:33:36.166] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:33:36.237] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:33:36.902] INFO: Expecting 41600 events.
[13:33:40.957] INFO: 41600 events read in total (3528ms).
[13:33:40.963] INFO: Test took 4726ms.
[13:33:41.116] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:41.116] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 918176
[13:33:41.117] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[13:33:41.117] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:33:41.185] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:33:41.853] INFO: Expecting 41600 events.
[13:33:45.915] INFO: 41600 events read in total (3536ms).
[13:33:45.922] INFO: Test took 4737ms.
[13:33:46.070] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:46.070] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 918771
[13:33:46.070] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[13:33:46.071] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:33:46.141] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:33:46.806] INFO: Expecting 41600 events.
[13:33:50.852] INFO: 41600 events read in total (3520ms).
[13:33:50.857] INFO: Test took 4716ms.
[13:33:51.005] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:51.005] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 917508
[13:33:51.005] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[13:33:51.006] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:33:51.076] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:33:51.742] INFO: Expecting 41600 events.
[13:33:55.784] INFO: 41600 events read in total (3515ms).
[13:33:55.789] INFO: Test took 4713ms.
[13:33:55.939] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:55.939] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 918798
[13:33:55.939] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[13:33:55.939] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:33:56.009] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:33:56.674] INFO: Expecting 41600 events.
[13:34:00.371] INFO: 41600 events read in total (3170ms).
[13:34:00.377] INFO: Test took 4368ms.
[13:34:00.524] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:00.524] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 907114
[13:34:00.524] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[13:34:00.525] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:34:00.816] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 163 eff = 0.998101
[13:34:00.816] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 154 eff = 0.999255
[13:34:00.817] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 186 eff = 0.99726
[13:34:00.817] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 196 eff = 0.995889
[13:34:00.817] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 166 eff = 0.994543
[13:34:00.817] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 161 eff = 0.994832
[13:34:00.817] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 153 eff = 0.994784
[13:34:00.817] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 179 eff = 0.995216
[13:34:00.817] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 142 eff = 0.995361
[13:34:00.818] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 167 eff = 0.994615
[13:34:00.818] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 182 eff = 0.994928
[13:34:00.818] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 162 eff = 0.994904
[13:34:00.818] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 164 eff = 0.994615
[13:34:00.818] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 165 eff = 0.99774
[13:34:00.818] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 143 eff = 0.999087
[13:34:00.818] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 167 eff = 0.998822
[13:34:27.184] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[13:34:27.184] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[13:34:27.184] INFO: ----------------------------------------------------------------------
[13:34:27.184] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:34:27.184] INFO: ----------------------------------------------------------------------
[13:34:27.184] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[13:34:27.184] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[13:34:27.184] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[13:34:27.184] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[13:34:27.184] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[13:34:27.184] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[13:34:27.184] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[13:34:27.184] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[13:34:27.184] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[13:34:27.184] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[13:34:27.184] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[13:34:27.184] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[13:34:27.202] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:34:27.876] INFO: Expecting 208000 events.
[13:34:45.089] INFO: 208000 events read in total (16686ms).
[13:34:45.111] INFO: Test took 17908ms.
[13:34:45.977] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:45.977] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4273306
[13:34:45.977] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[13:34:45.978] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:34:46.491] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:34:46.491] INFO: number of red-efficiency pixels: 328 212 456 800 912 948 847 785 785 917 886 844 817 508 241 185
[13:34:46.491] INFO: number of X-ray hits detected: 189905 126625 213533 317364 332170 325455 320538 298322 292893 335236 331655 315147 319716 210886 122736 154566
[13:34:46.491] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:34:46.491] INFO: number of Vcal hits detected: 207634 207770 207459 207089 206850 206857 206976 207089 207087 206916 206932 206927 207042 207424 207753 207809
[13:34:46.491] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.6 99.5 99.5 99.6 99.6 99.6 99.5 99.5 99.5 99.6 99.7 99.9 99.9
[13:34:46.491] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.4 99.5 99.5 99.6 99.6 99.5 99.5 99.5 99.5 99.7 99.9 99.9
[13:34:46.491] INFO: X-ray hit rate [MHz/cm2]: 55.7 37.1 62.6 93.0 97.4 95.4 94.0 87.4 85.8 98.3 97.2 92.4 93.7 61.8 36.0 45.3
[13:34:46.491] INFO: PixTestHighRate::doXPixelAlive() done
[13:34:46.537] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[13:34:46.537] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[13:34:46.537] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[13:34:46.537] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[13:34:46.537] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[13:34:46.537] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[13:34:46.537] INFO: PixTest:: pg_setup set to default.
[13:34:51.776] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[13:34:51.776] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[13:34:53.368] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[13:34:53.369] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.1mA
[13:34:53.369] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 472.7mA
[13:34:53.369] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[13:34:53.369] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[13:34:53.369] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[13:34:53.370] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[13:34:53.370] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[13:34:53.370] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[13:34:53.370] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[13:34:53.370] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[13:34:53.370] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[13:34:53.370] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[13:34:53.370] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[13:34:53.464] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[13:34:53.464] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[13:34:53.473] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[13:34:53.473] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[13:34:53.473] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[13:34:53.473] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[13:34:53.473] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[13:34:53.473] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[13:34:53.476] QUIET: Connection to board 58 closed.
[13:34:53.555] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries