[16:04:36.366] INFO: *** Welcome to pxar ***
[16:04:36.366] INFO: *** Today: 2016/09/22
[16:04:36.392] INFO: *** Version: v1.9.0-818-g96727
[16:04:36.392] INFO: readRocDacs: data/mqa09/dacParameters35_C0.dat .. data/mqa09/dacParameters35_C15.dat
[16:04:36.393] INFO: readTbmDacs: data/mqa09/tbmParameters_C0a.dat .. data/mqa09/tbmParameters_C0b.dat
[16:04:36.393] INFO: readMaskFile: data/mqa09/defaultMaskFile.dat
[16:04:36.393] INFO: readTrimFile: data/mqa09/trimParameters35_C0.dat .. data/mqa09/trimParameters35_C15.dat
[16:04:36.544] INFO: clk: 4
[16:04:36.544] INFO: ctr: 4
[16:04:36.544] INFO: sda: 19
[16:04:36.544] INFO: tin: 9
[16:04:36.544] INFO: level: 15
[16:04:36.544] INFO: triggerdelay: 0
[16:04:36.544] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:04:36.544] INFO: Log level: DEBUG
[16:04:36.555] QUIET: Connection to board DTB_WRPRHI opened.
[16:04:36.558] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:04:36.561] INFO: RPC call hashes of host and DTB match: 398089610
[16:04:38.164] INFO: DUT info:
[16:04:38.165] INFO: The DUT currently contains the following objects:
[16:04:38.165] INFO: 2 TBM Cores tbm08c (2 ON)
[16:04:38.165] INFO: TBM Core alpha (0): 7 registers set
[16:04:38.165] INFO: TBM Core beta (1): 7 registers set
[16:04:38.165] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:04:38.165] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:04:38.165] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:04:38.165] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:04:38.166] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:04:38.167] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:04:38.168] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:04:38.169] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:04:38.169] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:04:38.171] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33214464
[16:04:38.171] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2a070e0
[16:04:38.171] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x27a9370
[16:04:38.171] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fd529d94010
[16:04:38.171] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fd52ffff510
[16:04:38.171] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33222656 fPxarMemory = 0x7fd529d94010
[16:04:38.172] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 388.3mA
[16:04:38.173] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[16:04:38.173] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[16:04:38.670] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2df5ff0
[16:04:38.733] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:04:38.734] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:04:38.734] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:04:38.739] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:04:38.739] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:04:38.739] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:04:38.752] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:04:38.752] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:04:38.780] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:04:38.780] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:04:38.789] INFO: PixTestCmd::init()
[16:04:38.805] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:04:38.805] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:04:38.805] INFO: readGainPedestalParameters data/mqa09/phCalibrationFitErr35_C0.dat .. data/mqa09/phCalibrationFitErr35_C15.dat
[16:04:39.053] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:04:39.053] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:04:39.068] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:04:39.073] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:04:39.074] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:04:39.074] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:04:39.093] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:04:39.093] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:04:39.093] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:04:39.093] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:04:39.093] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:04:39.100] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:04:39.100] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:04:39.116] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:04:39.116] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:04:39.116] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:04:39.116] INFO: readReadbackCal: data/mqa09/readbackCal_C0.dat .. data/mqa09/readbackCal_C15.dat
[16:04:39.126] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:04:39.134] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:04:39.134] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:04:39.134] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:04:39.134] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:04:39.148] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:04:39.148] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:04:43.917] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[16:04:44.525] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[16:04:44.525] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[16:04:44.525] INFO: ----------------------------------------------------------------------
[16:04:44.525] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[16:04:44.525] INFO: ----------------------------------------------------------------------
[16:04:45.499] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[16:04:58.383] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[16:04:58.387] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:05:25.847] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1269451 events.
[16:05:31.644] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1269451, pixels seen in all events: 5018508
[16:05:31.813] INFO: Resuming triggers.
[16:05:44.703] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[16:05:44.741] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:06:12.062] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1269842 events.
[16:06:17.930] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1269842, pixels seen in all events: 5012792
[16:06:18.098] INFO: Resuming triggers.
[16:06:30.988] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[16:06:31.027] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:06:58.292] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1269917 events.
[16:07:04.072] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1269917, pixels seen in all events: 5011953
[16:07:04.298] INFO: Resuming triggers.
[16:07:17.189] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[16:07:17.230] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:07:44.577] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1269978 events.
[16:07:50.422] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1269978, pixels seen in all events: 5011378
[16:07:50.578] INFO: Resuming triggers.
[16:07:59.099] INFO: data taking finished, elapsed time: 60 seconds.
[16:07:59.295] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:08:17.313] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 839396 events.
[16:08:21.148] DEBUG: <PixTestXray.cc/processData:L823> # events read: 839396, pixels seen in all events: 3307328
[16:08:21.227] INFO: PixTest:: pg_setup set to default.
[16:08:21.310] INFO: PixTestXray::doPhRun() done
[16:08:26.557] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:08:26.558] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 391.5mA
[16:08:26.558] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.1mA
[16:08:26.558] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[16:08:26.558] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:08:26.559] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:08:26.559] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:08:26.559] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:08:26.559] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:08:26.559] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:08:26.559] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:08:26.559] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:08:26.559] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:08:26.559] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:08:26.559] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:08:26.559] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:08:26.559] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:08:26.559] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:08:26.559] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:08:26.560] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:08:26.560] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:08:26.560] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:08:26.831] QUIET: Connection to board 58 closed.
[16:08:26.911] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[16:08:37.536] INFO: *** Welcome to pxar ***
[16:08:37.536] INFO: *** Today: 2016/09/22
[16:08:37.559] INFO: *** Version: v1.9.0-818-g96727
[16:08:37.559] INFO: readRocDacs: data/mqa09/dacParameters35_C0.dat .. data/mqa09/dacParameters35_C15.dat
[16:08:37.560] INFO: readTbmDacs: data/mqa09/tbmParameters_C0a.dat .. data/mqa09/tbmParameters_C0b.dat
[16:08:37.561] INFO: readMaskFile: data/mqa09/defaultMaskFile.dat
[16:08:37.561] INFO: readTrimFile: data/mqa09/trimParameters35_C0.dat .. data/mqa09/trimParameters35_C15.dat
[16:08:37.710] INFO: clk: 4
[16:08:37.710] INFO: ctr: 4
[16:08:37.711] INFO: sda: 19
[16:08:37.711] INFO: tin: 9
[16:08:37.711] INFO: level: 15
[16:08:37.711] INFO: triggerdelay: 0
[16:08:37.711] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:08:37.711] INFO: Log level: DEBUG
[16:08:37.722] QUIET: Connection to board DTB_WRPRHI opened.
[16:08:37.725] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:08:37.728] INFO: RPC call hashes of host and DTB match: 398089610
[16:08:39.331] INFO: DUT info:
[16:08:39.331] INFO: The DUT currently contains the following objects:
[16:08:39.331] INFO: 2 TBM Cores tbm08c (2 ON)
[16:08:39.331] INFO: TBM Core alpha (0): 7 registers set
[16:08:39.331] INFO: TBM Core beta (1): 7 registers set
[16:08:39.331] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:08:39.331] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.331] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.331] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.331] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.331] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.331] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.332] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.332] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.332] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.332] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.332] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.332] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.332] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.332] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.332] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.332] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:08:39.332] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:08:39.333] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:08:39.334] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:08:39.335] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:08:39.337] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33431552
[16:08:39.338] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1c960e0
[16:08:39.338] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1a38370
[16:08:39.338] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f74c9d94010
[16:08:39.338] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f74cffff510
[16:08:39.338] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33439744 fPxarMemory = 0x7f74c9d94010
[16:08:39.339] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 388.3mA
[16:08:39.340] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[16:08:39.340] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[16:08:39.836] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2084f70
[16:08:39.900] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:08:39.901] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:08:39.901] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:08:39.906] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:08:39.906] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:08:39.907] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:08:39.919] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:08:39.919] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:08:39.940] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:08:39.940] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:08:39.951] INFO: PixTestCmd::init()
[16:08:39.962] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:08:39.962] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:08:39.962] INFO: readGainPedestalParameters data/mqa09/phCalibrationFitErr35_C0.dat .. data/mqa09/phCalibrationFitErr35_C15.dat
[16:08:40.213] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:08:40.213] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:08:40.228] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:08:40.234] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:08:40.234] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:08:40.234] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:08:40.256] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:08:40.256] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:08:40.256] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:08:40.256] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:08:40.256] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:08:40.264] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:08:40.264] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:08:40.288] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:08:40.288] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:08:40.288] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:08:40.288] INFO: readReadbackCal: data/mqa09/readbackCal_C0.dat .. data/mqa09/readbackCal_C15.dat
[16:08:40.300] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:08:40.314] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:08:40.315] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:08:40.315] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:08:40.315] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:08:40.328] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:08:40.328] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:08:49.949] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[16:08:50.709] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[16:08:50.709] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[16:08:50.709] INFO: ----------------------------------------------------------------------
[16:08:50.709] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[16:08:50.709] INFO: ----------------------------------------------------------------------
[16:08:51.683] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[16:08:59.593] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[16:08:59.597] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:09:25.075] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 779434 events.
[16:09:36.975] DEBUG: <PixTestXray.cc/processData:L823> # events read: 779434, pixels seen in all events: 10900174
[16:09:37.237] INFO: Resuming triggers.
[16:09:45.151] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[16:09:45.191] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:10:10.481] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 779642 events.
[16:10:22.333] DEBUG: <PixTestXray.cc/processData:L823> # events read: 779642, pixels seen in all events: 10896384
[16:10:22.575] INFO: Resuming triggers.
[16:10:30.486] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[16:10:30.525] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:10:55.843] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 779346 events.
[16:11:07.757] DEBUG: <PixTestXray.cc/processData:L823> # events read: 779346, pixels seen in all events: 10899477
[16:11:08.003] INFO: Resuming triggers.
[16:11:15.918] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[16:11:15.958] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:11:41.246] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 779726 events.
[16:11:53.099] DEBUG: <PixTestXray.cc/processData:L823> # events read: 779726, pixels seen in all events: 10895268
[16:11:53.298] INFO: Resuming triggers.
[16:12:01.216] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[16:12:01.300] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:12:26.621] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 779999 events.
[16:12:38.462] DEBUG: <PixTestXray.cc/processData:L823> # events read: 779999, pixels seen in all events: 10891496
[16:12:38.633] INFO: Resuming triggers.
[16:12:46.548] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[16:12:46.592] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:13:11.891] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 779776 events.
[16:13:23.765] DEBUG: <PixTestXray.cc/processData:L823> # events read: 779776, pixels seen in all events: 10895108
[16:13:24.005] INFO: Resuming triggers.
[16:13:31.923] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[16:13:31.961] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:13:57.300] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 780003 events.
[16:14:09.177] DEBUG: <PixTestXray.cc/processData:L823> # events read: 780003, pixels seen in all events: 10891420
[16:14:09.425] INFO: Resuming triggers.
[16:14:14.100] INFO: data taking finished, elapsed time: 60 seconds.
[16:14:14.296] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:14:29.239] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 460509 events.
[16:14:36.263] DEBUG: <PixTestXray.cc/processData:L823> # events read: 460509, pixels seen in all events: 6426882
[16:14:36.386] INFO: PixTest:: pg_setup set to default.
[16:14:36.428] INFO: PixTestXray::doPhRun() done
[16:14:47.381] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:14:47.382] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 391.5mA
[16:14:47.383] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[16:14:47.383] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:14:47.383] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:14:47.383] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:14:47.383] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:14:47.383] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:14:47.383] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:14:47.383] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:14:47.383] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:14:47.383] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:14:47.383] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:14:47.384] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:14:47.384] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:14:47.384] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:14:47.384] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:14:47.384] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:14:47.384] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:14:47.384] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:14:47.384] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:14:47.384] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:14:47.695] QUIET: Connection to board 58 closed.
[16:14:47.774] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[15:57:30.267] INFO: *** Welcome to pxar ***
[15:57:30.267] INFO: *** Today: 2016/09/22
[15:57:30.290] INFO: *** Version: v1.9.0-818-g96727
[15:57:30.290] INFO: readRocDacs: data/mqa09/dacParameters35_C0.dat .. data/mqa09/dacParameters35_C15.dat
[15:57:30.291] INFO: readTbmDacs: data/mqa09/tbmParameters_C0a.dat .. data/mqa09/tbmParameters_C0b.dat
[15:57:30.292] INFO: readMaskFile: data/mqa09/defaultMaskFile.dat
[15:57:30.292] INFO: readTrimFile: data/mqa09/trimParameters35_C0.dat .. data/mqa09/trimParameters35_C15.dat
[15:57:30.441] INFO: clk: 4
[15:57:30.441] INFO: ctr: 4
[15:57:30.441] INFO: sda: 19
[15:57:30.441] INFO: tin: 9
[15:57:30.441] INFO: level: 15
[15:57:30.441] INFO: triggerdelay: 0
[15:57:30.441] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:57:30.441] INFO: Log level: DEBUG
[15:57:30.452] QUIET: Connection to board DTB_WRPRHI opened.
[15:57:30.455] 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:
------------------------------------------------------
[15:57:30.458] INFO: RPC call hashes of host and DTB match: 398089610
[15:57:32.062] INFO: DUT info:
[15:57:32.062] INFO: The DUT currently contains the following objects:
[15:57:32.062] INFO: 2 TBM Cores tbm08c (2 ON)
[15:57:32.062] INFO: TBM Core alpha (0): 7 registers set
[15:57:32.062] INFO: TBM Core beta (1): 7 registers set
[15:57:32.062] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:57:32.062] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.062] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.062] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.062] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.062] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.062] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.062] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.062] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.062] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.062] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.062] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.062] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.063] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.063] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.063] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.063] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:57:32.063] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:57:32.064] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:57:32.065] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:57:32.066] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:57:32.068] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33005568
[15:57:32.068] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1a380e0
[15:57:32.068] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x17da370
[15:57:32.068] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f7aedd94010
[15:57:32.068] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f7af3fff510
[15:57:32.068] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33013760 fPxarMemory = 0x7f7aedd94010
[15:57:32.070] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 387.5mA
[15:57:32.071] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[15:57:32.071] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[15:57:32.569] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1e26f10
[15:57:32.630] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:57:32.630] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:57:32.631] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:57:32.636] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:57:32.636] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:57:32.636] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:57:32.650] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:57:32.650] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:57:32.662] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:57:32.662] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:57:32.678] INFO: PixTestCmd::init()
[15:57:32.689] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:57:32.689] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:57:32.689] INFO: readGainPedestalParameters data/mqa09/phCalibrationFitErr35_C0.dat .. data/mqa09/phCalibrationFitErr35_C15.dat
[15:57:32.937] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:57:32.937] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:57:32.954] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:57:32.960] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:57:32.961] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:57:32.961] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:57:32.984] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:57:32.984] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:57:32.984] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:57:32.984] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:57:32.984] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:57:32.992] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:57:32.992] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:57:33.009] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:57:33.009] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:57:33.009] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:57:33.009] INFO: readReadbackCal: data/mqa09/readbackCal_C0.dat .. data/mqa09/readbackCal_C15.dat
[15:57:33.019] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:57:33.029] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:57:33.029] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:57:33.029] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:57:33.029] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:57:33.046] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:57:33.046] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:57:36.624] INFO: ######################################################################
[15:57:36.624] INFO: PixTestAlive::doTest()
[15:57:36.624] INFO: ######################################################################
[15:57:36.628] INFO: ----------------------------------------------------------------------
[15:57:36.628] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:57:36.628] INFO: ----------------------------------------------------------------------
[15:57:36.630] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:57:37.008] INFO: Expecting 41600 events.
[15:57:41.429] INFO: 41600 events read in total (3703ms).
[15:57:41.577] INFO: Test took 4947ms.
[15:57:41.587] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:41.587] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:57:41.587] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:57:41.865] INFO: PixTestAlive::aliveTest() done
[15:57:41.865] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[15:57:41.865] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 25 21 26 29 41 49 28 35 42 50 32 33 39 22 15 18
[15:57:41.891] INFO: ----------------------------------------------------------------------
[15:57:41.891] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:57:41.891] INFO: ----------------------------------------------------------------------
[15:57:41.894] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:57:42.270] INFO: Expecting 41600 events.
[15:57:45.372] INFO: 41600 events read in total (2387ms).
[15:57:45.372] INFO: Test took 3478ms.
[15:57:45.372] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:45.372] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:57:45.372] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:57:45.373] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:57:45.795] INFO: PixTestAlive::maskTest() done
[15:57:45.795] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:57:45.818] INFO: ----------------------------------------------------------------------
[15:57:45.818] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:57:45.818] INFO: ----------------------------------------------------------------------
[15:57:45.820] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:57:46.197] INFO: Expecting 41600 events.
[15:57:50.579] INFO: 41600 events read in total (3667ms).
[15:57:50.580] INFO: Test took 4760ms.
[15:57:50.590] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:50.590] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:57:50.590] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:57:50.962] INFO: PixTestAlive::addressDecodingTest() done
[15:57:50.962] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:57:50.962] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:58:08.565] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:58:08.565] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[15:58:11.837] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:58:11.837] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:58:11.837] INFO: ----------------------------------------------------------------------
[15:58:11.837] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:58:11.837] INFO: ----------------------------------------------------------------------
[15:58:11.976] INFO: Expecting 768 events.
[15:58:13.110] INFO: 768 events read in total (419ms).
[15:58:13.111] INFO: Test took 1268ms.
[15:58:13.117] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:13.914] INFO: Expecting 41600 events.
[15:58:17.172] INFO: 41600 events read in total (2731ms).
[15:58:17.176] INFO: Test took 4059ms.
[15:58:17.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:17.249] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 439225
[15:58:17.249] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:58:17.250] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:17.286] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:17.951] INFO: Expecting 41600 events.
[15:58:21.356] INFO: 41600 events read in total (2878ms).
[15:58:21.360] INFO: Test took 4074ms.
[15:58:21.434] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:21.434] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 449841
[15:58:21.435] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:58:21.435] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:21.471] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:22.136] INFO: Expecting 41600 events.
[15:58:25.588] INFO: 41600 events read in total (2925ms).
[15:58:25.592] INFO: Test took 4121ms.
[15:58:25.669] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:25.669] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452530
[15:58:25.669] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:58:25.669] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:25.707] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:26.371] INFO: Expecting 41600 events.
[15:58:29.775] INFO: 41600 events read in total (2877ms).
[15:58:29.778] INFO: Test took 4071ms.
[15:58:29.853] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:29.853] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452372
[15:58:29.853] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:58:29.853] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:29.891] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:30.556] INFO: Expecting 41600 events.
[15:58:34.015] INFO: 41600 events read in total (2932ms).
[15:58:34.018] INFO: Test took 4127ms.
[15:58:34.093] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:34.093] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452031
[15:58:34.093] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:58:34.093] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:34.131] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:34.796] INFO: Expecting 41600 events.
[15:58:38.255] INFO: 41600 events read in total (2932ms).
[15:58:38.259] INFO: Test took 4128ms.
[15:58:38.333] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:38.333] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 450507
[15:58:38.333] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:58:38.333] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:38.371] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:39.036] INFO: Expecting 41600 events.
[15:58:42.517] INFO: 41600 events read in total (2954ms).
[15:58:42.520] INFO: Test took 4149ms.
[15:58:42.598] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:42.598] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452712
[15:58:42.599] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:58:42.599] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:42.637] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:43.302] INFO: Expecting 41600 events.
[15:58:46.774] INFO: 41600 events read in total (2946ms).
[15:58:46.778] INFO: Test took 4141ms.
[15:58:46.852] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:46.852] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 451307
[15:58:46.852] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:58:46.853] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:46.890] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:47.556] INFO: Expecting 41600 events.
[15:58:51.028] INFO: 41600 events read in total (2945ms).
[15:58:51.032] INFO: Test took 4142ms.
[15:58:51.106] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:51.106] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 453325
[15:58:51.106] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:58:51.106] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:51.144] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:51.809] INFO: Expecting 41600 events.
[15:58:55.292] INFO: 41600 events read in total (2956ms).
[15:58:55.296] INFO: Test took 4152ms.
[15:58:55.371] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:55.371] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452629
[15:58:55.371] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:58:55.371] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:55.409] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:56.074] INFO: Expecting 41600 events.
[15:58:59.537] INFO: 41600 events read in total (2936ms).
[15:58:59.540] INFO: Test took 4131ms.
[15:58:59.615] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:59.615] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 451993
[15:58:59.615] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:58:59.615] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:59.653] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:59:00.318] INFO: Expecting 41600 events.
[15:59:03.796] INFO: 41600 events read in total (2952ms).
[15:59:03.799] INFO: Test took 4146ms.
[15:59:03.874] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:03.874] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452644
[15:59:03.874] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:59:03.874] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:59:03.912] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:59:04.577] INFO: Expecting 41600 events.
[15:59:08.062] INFO: 41600 events read in total (2958ms).
[15:59:08.066] INFO: Test took 4154ms.
[15:59:08.140] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:08.140] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 453101
[15:59:08.140] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:59:08.140] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:59:08.178] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:59:08.843] INFO: Expecting 41600 events.
[15:59:12.306] INFO: 41600 events read in total (2936ms).
[15:59:12.309] INFO: Test took 4131ms.
[15:59:12.383] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:12.383] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 451051
[15:59:12.383] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:59:12.383] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:59:12.421] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:59:13.086] INFO: Expecting 41600 events.
[15:59:16.554] INFO: 41600 events read in total (2941ms).
[15:59:16.557] INFO: Test took 4136ms.
[15:59:16.633] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:16.633] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 451989
[15:59:16.633] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:59:16.634] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:59:16.672] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:59:17.337] INFO: Expecting 41600 events.
[15:59:20.812] INFO: 41600 events read in total (2948ms).
[15:59:20.815] INFO: Test took 4143ms.
[15:59:20.890] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:20.890] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452020
[15:59:20.890] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:59:20.890] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:59:20.928] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:59:21.593] INFO: Expecting 41600 events.
[15:59:25.070] INFO: 41600 events read in total (2950ms).
[15:59:25.073] INFO: Test took 4145ms.
[15:59:25.148] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:25.148] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 450577
[15:59:25.148] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:59:25.148] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:59:25.186] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:59:25.852] INFO: Expecting 41600 events.
[15:59:29.330] INFO: 41600 events read in total (2951ms).
[15:59:29.333] INFO: Test took 4147ms.
[15:59:29.410] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:29.410] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452835
[15:59:29.410] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:59:29.410] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:59:29.450] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:59:30.116] INFO: Expecting 41600 events.
[15:59:33.575] INFO: 41600 events read in total (2932ms).
[15:59:33.578] INFO: Test took 4128ms.
[15:59:33.654] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:33.654] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 451372
[15:59:33.654] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:59:33.655] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:59:33.694] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:59:34.360] INFO: Expecting 41600 events.
[15:59:37.671] INFO: 41600 events read in total (2784ms).
[15:59:37.674] INFO: Test took 3980ms.
[15:59:37.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:37.748] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 444555
[15:59:37.748] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:59:37.749] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:59:38.007] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 142 eff = 0.999639
[15:59:38.007] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 165 eff = 0.999736
[15:59:38.007] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 147 eff = 0.999567
[15:59:38.008] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 149 eff = 0.999183
[15:59:38.008] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 166 eff = 0.999111
[15:59:38.008] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 157 eff = 0.999087
[15:59:38.008] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 141 eff = 0.99887
[15:59:38.008] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 171 eff = 0.999207
[15:59:38.008] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 199 eff = 0.999038
[15:59:38.008] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 153 eff = 0.998966
[15:59:38.009] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 157 eff = 0.999135
[15:59:38.009] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 184 eff = 0.999183
[15:59:38.009] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 174 eff = 0.999183
[15:59:38.009] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 175 eff = 0.999591
[15:59:38.009] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 143 eff = 0.99976
[15:59:38.009] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 141 eff = 0.999447
[15:59:49.309] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:59:49.309] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:59:49.309] INFO: ----------------------------------------------------------------------
[15:59:49.309] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:59:49.309] INFO: ----------------------------------------------------------------------
[15:59:49.309] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:59:49.309] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:59:49.309] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:59:49.309] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:59:49.309] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:59:49.309] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:59:49.310] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:59:49.310] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:59:49.310] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:59:49.310] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:59:49.310] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:59:49.310] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:59:49.327] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:59:49.996] INFO: Expecting 208000 events.
[16:00:03.343] INFO: 208000 events read in total (12820ms).
[16:00:03.354] INFO: Test took 14027ms.
[16:00:03.696] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:00:03.696] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1981134
[16:00:03.696] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:00:03.696] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:00:04.064] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[16:00:04.064] INFO: number of red-efficiency pixels: 116 79 137 195 223 218 218 178 188 230 207 197 221 119 78 94
[16:00:04.064] INFO: number of X-ray hits detected: 84969 56549 94835 140489 150484 149597 145959 139937 145896 151948 149576 142784 142233 93803 57020 68496
[16:00:04.064] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:00:04.064] INFO: number of Vcal hits detected: 207884 207920 207862 207798 207773 207777 207776 207816 207806 207762 207786 207796 207772 207878 207919 207856
[16:00:04.064] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[16:00:04.064] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[16:00:04.064] INFO: X-ray hit rate [MHz/cm2]: 24.9 16.6 27.8 41.2 44.1 43.8 42.8 41.0 42.8 44.5 43.8 41.9 41.7 27.5 16.7 20.1
[16:00:04.064] INFO: PixTestHighRate::doXPixelAlive() done
[16:00:04.111] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:00:04.111] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:00:04.111] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:00:04.111] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:00:04.111] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:00:04.111] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:00:04.111] INFO: PixTest:: pg_setup set to default.
[16:00:12.533] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:00:12.533] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[16:00:14.341] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[16:00:14.341] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[16:00:14.341] INFO: ----------------------------------------------------------------------
[16:00:14.341] INFO: PixTestHighRate::runDaq() running for 2 seconds
[16:00:14.341] INFO: ----------------------------------------------------------------------
[16:00:14.548] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[16:00:14.548] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[16:00:14.548] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[16:00:14.548] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[16:00:14.548] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[16:00:14.548] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[16:00:14.548] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[16:00:14.548] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[16:00:14.549] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[16:00:14.549] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[16:00:14.549] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[16:00:14.549] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[16:00:15.506] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[16:00:17.509] INFO: data taking finished, elapsed time: 2 seconds.
[16:00:18.979] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39827 events with 363520 pixels
[16:00:18.985] INFO: PixTest:: pg_setup set to default.
[16:00:19.126] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[16:00:19.126] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[16:00:19.127] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[16:00:19.127] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[16:00:19.127] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[16:00:19.127] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[16:00:19.127] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[16:00:19.127] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[16:00:19.128] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[16:00:19.128] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[16:00:19.128] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[16:00:19.128] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[16:00:19.128] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[16:00:19.128] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[16:00:19.128] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[16:00:19.129] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[16:00:19.129] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[16:00:19.129] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[16:00:19.129] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[16:00:19.129] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[16:00:19.129] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[16:00:19.129] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[16:00:19.129] INFO: Pixels without X-ray hits (per ROC): 762 1349 790 226 205 244 248 169 215 249 234 344 230 776 1372 940
[16:00:19.129] INFO: PixTestHighRate::doRunDaq() done
[16:00:30.517] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:00:30.517] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C12_V0 -> hitMap_daqbbtest_mod
[16:00:31.981] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:00:31.982] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 391.5mA
[16:00:31.983] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[16:00:31.983] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[16:00:31.983] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:00:31.983] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:00:31.983] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:00:31.983] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:00:31.983] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:00:31.983] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:00:31.983] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:00:31.983] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:00:31.983] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:00:31.983] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:00:32.088] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:00:32.088] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:00:32.101] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:00:32.101] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:00:32.101] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:00:32.101] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:00:32.102] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:00:32.102] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:00:32.104] QUIET: Connection to board 58 closed.
[16:00:32.184] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[15:53:30.387] INFO: *** Welcome to pxar ***
[15:53:30.387] INFO: *** Today: 2016/09/22
[15:53:30.412] INFO: *** Version: v1.9.0-818-g96727
[15:53:30.412] INFO: readRocDacs: data/mqa09/dacParameters35_C0.dat .. data/mqa09/dacParameters35_C15.dat
[15:53:30.413] INFO: readTbmDacs: data/mqa09/tbmParameters_C0a.dat .. data/mqa09/tbmParameters_C0b.dat
[15:53:30.413] INFO: readMaskFile: data/mqa09/defaultMaskFile.dat
[15:53:30.413] INFO: readTrimFile: data/mqa09/trimParameters35_C0.dat .. data/mqa09/trimParameters35_C15.dat
[15:53:30.562] INFO: clk: 4
[15:53:30.562] INFO: ctr: 4
[15:53:30.562] INFO: sda: 19
[15:53:30.562] INFO: tin: 9
[15:53:30.562] INFO: level: 15
[15:53:30.562] INFO: triggerdelay: 0
[15:53:30.562] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:53:30.562] INFO: Log level: DEBUG
[15:53:30.573] QUIET: Connection to board DTB_WRPRHI opened.
[15:53:30.576] 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:
------------------------------------------------------
[15:53:30.579] INFO: RPC call hashes of host and DTB match: 398089610
[15:53:32.183] INFO: DUT info:
[15:53:32.183] INFO: The DUT currently contains the following objects:
[15:53:32.183] INFO: 2 TBM Cores tbm08c (2 ON)
[15:53:32.183] INFO: TBM Core alpha (0): 7 registers set
[15:53:32.183] INFO: TBM Core beta (1): 7 registers set
[15:53:32.183] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:53:32.183] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.183] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.183] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.183] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.183] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.183] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.183] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.183] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.183] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.183] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.183] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.183] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.183] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.183] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.183] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.183] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:53:32.184] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:53:32.185] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:53:32.186] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:53:32.187] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:53:32.189] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32731136
[15:53:32.189] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x23ee0e0
[15:53:32.189] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2190370
[15:53:32.189] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f2ea1d94010
[15:53:32.189] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f2ea7fff510
[15:53:32.189] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32739328 fPxarMemory = 0x7f2ea1d94010
[15:53:32.190] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 388.3mA
[15:53:32.192] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[15:53:32.192] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:53:32.691] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x27dcfc0
[15:53:32.754] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:53:32.754] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:53:32.754] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:53:32.760] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:53:32.760] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:53:32.760] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:53:32.772] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:53:32.772] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:53:32.791] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:53:32.791] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:53:32.800] INFO: PixTestCmd::init()
[15:53:32.811] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:53:32.811] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:53:32.811] INFO: readGainPedestalParameters data/mqa09/phCalibrationFitErr35_C0.dat .. data/mqa09/phCalibrationFitErr35_C15.dat
[15:53:33.058] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:53:33.058] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:53:33.072] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:53:33.078] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:53:33.078] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:53:33.078] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:53:33.102] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:53:33.102] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:53:33.102] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:53:33.102] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:53:33.102] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:53:33.108] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:53:33.108] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:53:33.130] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:53:33.130] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:53:33.130] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:53:33.130] INFO: readReadbackCal: data/mqa09/readbackCal_C0.dat .. data/mqa09/readbackCal_C15.dat
[15:53:33.140] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:53:33.148] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:53:33.149] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:53:33.149] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:53:33.149] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:53:33.166] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:53:33.166] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:53:50.720] INFO: ######################################################################
[15:53:50.720] INFO: PixTestAlive::doTest()
[15:53:50.720] INFO: ######################################################################
[15:53:50.723] INFO: ----------------------------------------------------------------------
[15:53:50.723] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:53:50.724] INFO: ----------------------------------------------------------------------
[15:53:50.726] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:53:51.105] INFO: Expecting 41600 events.
[15:53:55.496] INFO: 41600 events read in total (3673ms).
[15:53:55.643] INFO: Test took 4917ms.
[15:53:55.654] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:55.654] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:53:55.654] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:53:55.932] INFO: PixTestAlive::aliveTest() done
[15:53:55.932] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[15:53:55.932] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 41 24 41 52 67 56 47 45 65 62 55 50 58 35 18 22
[15:53:55.958] INFO: ----------------------------------------------------------------------
[15:53:55.958] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:53:55.958] INFO: ----------------------------------------------------------------------
[15:53:55.960] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:53:56.337] INFO: Expecting 41600 events.
[15:53:59.453] INFO: 41600 events read in total (2401ms).
[15:53:59.453] INFO: Test took 3493ms.
[15:53:59.453] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:59.453] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:53:59.453] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:53:59.454] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:53:59.876] INFO: PixTestAlive::maskTest() done
[15:53:59.876] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:53:59.899] INFO: ----------------------------------------------------------------------
[15:53:59.899] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:53:59.899] INFO: ----------------------------------------------------------------------
[15:53:59.901] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:54:00.280] INFO: Expecting 41600 events.
[15:54:04.679] INFO: 41600 events read in total (3684ms).
[15:54:04.680] INFO: Test took 4779ms.
[15:54:04.690] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:04.690] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:54:04.690] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:54:05.061] INFO: PixTestAlive::addressDecodingTest() done
[15:54:05.061] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:54:05.061] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:54:17.717] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:54:17.717] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[15:54:23.725] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:54:23.725] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:54:23.725] INFO: ----------------------------------------------------------------------
[15:54:23.725] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:54:23.725] INFO: ----------------------------------------------------------------------
[15:54:23.864] INFO: Expecting 768 events.
[15:54:24.997] INFO: 768 events read in total (418ms).
[15:54:24.997] INFO: Test took 1266ms.
[15:54:25.003] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:54:25.803] INFO: Expecting 41600 events.
[15:54:29.335] INFO: 41600 events read in total (3005ms).
[15:54:29.340] INFO: Test took 4337ms.
[15:54:29.456] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:29.456] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 643710
[15:54:29.456] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:54:29.457] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:54:29.505] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:54:30.170] INFO: Expecting 41600 events.
[15:54:33.820] INFO: 41600 events read in total (3123ms).
[15:54:33.826] INFO: Test took 4321ms.
[15:54:33.938] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:33.938] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 652047
[15:54:33.938] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:54:33.938] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:54:33.989] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:54:34.654] INFO: Expecting 41600 events.
[15:54:38.362] INFO: 41600 events read in total (3181ms).
[15:54:38.367] INFO: Test took 4378ms.
[15:54:38.477] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:38.477] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 656425
[15:54:38.477] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:54:38.478] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:54:38.529] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:54:39.194] INFO: Expecting 41600 events.
[15:54:42.919] INFO: 41600 events read in total (3198ms).
[15:54:42.923] INFO: Test took 4394ms.
[15:54:43.034] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:43.034] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 658159
[15:54:43.034] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:54:43.035] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:54:43.086] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:54:43.751] INFO: Expecting 41600 events.
[15:54:47.475] INFO: 41600 events read in total (3197ms).
[15:54:47.479] INFO: Test took 4393ms.
[15:54:47.591] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:47.591] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 656838
[15:54:47.591] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:54:47.592] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:54:47.643] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:54:48.309] INFO: Expecting 41600 events.
[15:54:52.036] INFO: 41600 events read in total (3201ms).
[15:54:52.041] INFO: Test took 4398ms.
[15:54:52.152] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:52.152] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 657345
[15:54:52.152] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:54:52.153] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:54:52.205] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:54:52.869] INFO: Expecting 41600 events.
[15:54:56.617] INFO: 41600 events read in total (3221ms).
[15:54:56.621] INFO: Test took 4416ms.
[15:54:56.731] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:56.732] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 658108
[15:54:56.732] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:54:56.732] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:54:56.783] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:54:57.449] INFO: Expecting 41600 events.
[15:55:01.188] INFO: 41600 events read in total (3213ms).
[15:55:01.192] INFO: Test took 4409ms.
[15:55:01.306] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:01.306] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 658213
[15:55:01.306] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:55:01.306] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:01.358] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:02.023] INFO: Expecting 41600 events.
[15:55:05.754] INFO: 41600 events read in total (3204ms).
[15:55:05.758] INFO: Test took 4400ms.
[15:55:05.870] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:05.870] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 657115
[15:55:05.870] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:55:05.870] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:05.922] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:06.588] INFO: Expecting 41600 events.
[15:55:10.295] INFO: 41600 events read in total (3181ms).
[15:55:10.300] INFO: Test took 4378ms.
[15:55:10.409] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:10.409] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 658101
[15:55:10.409] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:55:10.410] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:10.462] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:11.127] INFO: Expecting 41600 events.
[15:55:14.869] INFO: 41600 events read in total (3215ms).
[15:55:14.873] INFO: Test took 4411ms.
[15:55:14.984] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:14.984] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 657512
[15:55:14.984] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:55:14.985] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:15.036] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:15.701] INFO: Expecting 41600 events.
[15:55:19.439] INFO: 41600 events read in total (3211ms).
[15:55:19.443] INFO: Test took 4407ms.
[15:55:19.554] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:19.554] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 657093
[15:55:19.554] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:55:19.555] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:19.609] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:20.276] INFO: Expecting 41600 events.
[15:55:24.015] INFO: 41600 events read in total (3212ms).
[15:55:24.019] INFO: Test took 4410ms.
[15:55:24.133] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:24.133] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 659355
[15:55:24.133] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:55:24.133] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:24.186] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:24.853] INFO: Expecting 41600 events.
[15:55:28.588] INFO: 41600 events read in total (3208ms).
[15:55:28.592] INFO: Test took 4406ms.
[15:55:28.703] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:28.703] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 658494
[15:55:28.703] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:55:28.704] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:28.757] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:29.423] INFO: Expecting 41600 events.
[15:55:33.158] INFO: 41600 events read in total (3208ms).
[15:55:33.162] INFO: Test took 4405ms.
[15:55:33.318] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:33.318] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 656745
[15:55:33.318] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:55:33.318] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:33.370] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:34.035] INFO: Expecting 41600 events.
[15:55:37.770] INFO: 41600 events read in total (3208ms).
[15:55:37.775] INFO: Test took 4405ms.
[15:55:37.885] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:37.885] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 656597
[15:55:37.886] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:55:37.886] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:37.939] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:38.604] INFO: Expecting 41600 events.
[15:55:42.328] INFO: 41600 events read in total (3198ms).
[15:55:42.333] INFO: Test took 4394ms.
[15:55:42.443] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:42.443] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 657944
[15:55:42.443] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:55:42.444] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:42.496] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:43.161] INFO: Expecting 41600 events.
[15:55:46.897] INFO: 41600 events read in total (3209ms).
[15:55:46.902] INFO: Test took 4406ms.
[15:55:47.014] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:47.015] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 658193
[15:55:47.015] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:55:47.015] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:47.068] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:47.735] INFO: Expecting 41600 events.
[15:55:51.450] INFO: 41600 events read in total (3188ms).
[15:55:51.454] INFO: Test took 4386ms.
[15:55:51.564] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:51.565] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 656685
[15:55:51.565] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:55:51.565] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:51.617] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:52.282] INFO: Expecting 41600 events.
[15:55:55.789] INFO: 41600 events read in total (2980ms).
[15:55:55.794] INFO: Test took 4177ms.
[15:55:55.901] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:55.901] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 647198
[15:55:55.901] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:55:55.902] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:56.174] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 151 eff = 0.999303
[15:55:56.174] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 156 eff = 0.999591
[15:55:56.175] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 151 eff = 0.998774
[15:55:56.175] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 152 eff = 0.998221
[15:55:56.175] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 163 eff = 0.998053
[15:55:56.175] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 157 eff = 0.998029
[15:55:56.176] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 127 eff = 0.997837
[15:55:56.176] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 170 eff = 0.998293
[15:55:56.176] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 205 eff = 0.998149
[15:55:56.176] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 153 eff = 0.997813
[15:55:56.177] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 155 eff = 0.997981
[15:55:56.177] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 181 eff = 0.998005
[15:55:56.177] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 173 eff = 0.998582
[15:55:56.177] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 146 eff = 0.998678
[15:55:56.178] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 166 eff = 0.999688
[15:55:56.178] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 150 eff = 0.999255
[15:56:04.061] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:56:04.061] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:56:04.061] INFO: ----------------------------------------------------------------------
[15:56:04.061] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:56:04.061] INFO: ----------------------------------------------------------------------
[15:56:04.061] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:56:04.061] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:56:04.061] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:56:04.061] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:56:04.061] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:56:04.061] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:56:04.061] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:56:04.061] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:56:04.061] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:56:04.061] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:56:04.062] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:56:04.062] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:56:04.079] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:56:04.747] INFO: Expecting 208000 events.
[15:56:19.671] INFO: 208000 events read in total (14398ms).
[15:56:19.687] INFO: Test took 15608ms.
[15:56:20.213] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:20.214] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2989506
[15:56:20.214] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:56:20.214] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:56:20.649] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[15:56:20.649] INFO: number of red-efficiency pixels: 200 141 232 395 441 427 510 345 406 433 425 437 369 323 109 148
[15:56:20.649] INFO: number of X-ray hits detected: 129322 86233 145623 213868 227344 228288 223199 214750 222767 232255 228146 219173 216244 143887 86410 105438
[15:56:20.649] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:56:20.649] INFO: number of Vcal hits detected: 207792 207851 207749 207544 207533 207539 207431 207628 207565 207524 207535 207523 207604 207646 207889 207798
[15:56:20.649] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 100.0 99.9
[15:56:20.649] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9
[15:56:20.649] INFO: X-ray hit rate [MHz/cm2]: 37.9 25.3 42.7 62.7 66.6 66.9 65.4 62.9 65.3 68.1 66.9 64.2 63.4 42.2 25.3 30.9
[15:56:20.649] INFO: PixTestHighRate::doXPixelAlive() done
[15:56:20.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:56:20.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:56:20.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:56:20.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:56:20.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:56:20.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:56:20.695] INFO: PixTest:: pg_setup set to default.
[15:56:34.469] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:56:34.469] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[15:56:38.517] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[15:56:38.517] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[15:56:38.517] INFO: ----------------------------------------------------------------------
[15:56:38.517] INFO: PixTestHighRate::runDaq() running for 2 seconds
[15:56:38.517] INFO: ----------------------------------------------------------------------
[15:56:38.719] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[15:56:38.719] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[15:56:38.719] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[15:56:38.719] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[15:56:38.719] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[15:56:38.719] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[15:56:38.719] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[15:56:38.719] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[15:56:38.719] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[15:56:38.719] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[15:56:38.719] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[15:56:38.719] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[15:56:39.677] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[15:56:41.680] INFO: data taking finished, elapsed time: 2 seconds.
[15:56:43.388] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39815 events with 556412 pixels
[15:56:43.395] INFO: PixTest:: pg_setup set to default.
[15:56:43.517] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[15:56:43.517] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[15:56:43.517] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[15:56:43.518] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[15:56:43.518] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[15:56:43.518] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[15:56:43.518] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[15:56:43.518] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[15:56:43.518] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[15:56:43.518] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[15:56:43.518] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[15:56:43.519] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[15:56:43.519] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[15:56:43.519] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[15:56:43.519] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[15:56:43.519] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[15:56:43.520] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[15:56:43.520] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[15:56:43.520] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[15:56:43.520] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[15:56:43.520] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[15:56:43.520] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[15:56:43.520] INFO: Pixels without X-ray hits (per ROC): 452 1048 578 122 118 131 149 108 117 115 132 206 134 587 1031 625
[15:56:43.520] INFO: PixTestHighRate::doRunDaq() done
[15:56:57.205] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:56:57.205] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C12_V0 -> hitMap_daqbbtest_mod
[15:56:59.045] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:56:59.045] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 391.5mA
[15:56:59.046] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[15:56:59.046] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[15:56:59.046] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:56:59.046] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:56:59.046] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:56:59.047] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:56:59.047] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:56:59.047] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:56:59.047] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:56:59.047] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:56:59.047] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:56:59.047] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:56:59.153] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:56:59.153] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:56:59.166] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:56:59.166] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:56:59.166] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:56:59.166] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:56:59.166] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:56:59.166] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:56:59.169] QUIET: Connection to board 58 closed.
[15:56:59.249] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[15:49:37.633] INFO: *** Welcome to pxar ***
[15:49:37.633] INFO: *** Today: 2016/09/22
[15:49:37.657] INFO: *** Version: v1.9.0-818-g96727
[15:49:37.657] INFO: readRocDacs: data/mqa09/dacParameters35_C0.dat .. data/mqa09/dacParameters35_C15.dat
[15:49:37.658] INFO: readTbmDacs: data/mqa09/tbmParameters_C0a.dat .. data/mqa09/tbmParameters_C0b.dat
[15:49:37.658] INFO: readMaskFile: data/mqa09/defaultMaskFile.dat
[15:49:37.658] INFO: readTrimFile: data/mqa09/trimParameters35_C0.dat .. data/mqa09/trimParameters35_C15.dat
[15:49:37.808] INFO: clk: 4
[15:49:37.808] INFO: ctr: 4
[15:49:37.808] INFO: sda: 19
[15:49:37.808] INFO: tin: 9
[15:49:37.808] INFO: level: 15
[15:49:37.808] INFO: triggerdelay: 0
[15:49:37.808] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:49:37.808] INFO: Log level: DEBUG
[15:49:37.819] QUIET: Connection to board DTB_WRPRHI opened.
[15:49:37.823] 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:
------------------------------------------------------
[15:49:37.825] INFO: RPC call hashes of host and DTB match: 398089610
[15:49:39.427] INFO: DUT info:
[15:49:39.427] INFO: The DUT currently contains the following objects:
[15:49:39.427] INFO: 2 TBM Cores tbm08c (2 ON)
[15:49:39.427] INFO: TBM Core alpha (0): 7 registers set
[15:49:39.427] INFO: TBM Core beta (1): 7 registers set
[15:49:39.427] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:49:39.427] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.427] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.427] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.427] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.428] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.428] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.428] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.428] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.428] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.428] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.428] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.428] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.428] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.428] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.428] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.428] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:49:39.428] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:49:39.428] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:49:39.428] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:49:39.428] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:49:39.428] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:49:39.428] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:49:39.428] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:49:39.428] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:49:39.428] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:49:39.428] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:49:39.428] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:49:39.428] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:49:39.428] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:49:39.428] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:49:39.428] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:49:39.429] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:49:39.430] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:49:39.431] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:49:39.433] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34828288
[15:49:39.433] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x28700e0
[15:49:39.433] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2612370
[15:49:39.433] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f4039d94010
[15:49:39.433] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f403ffff510
[15:49:39.433] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34836480 fPxarMemory = 0x7f4039d94010
[15:49:39.435] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 388.3mA
[15:49:39.436] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[15:49:39.436] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[15:49:39.955] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2c5f030
[15:49:40.035] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:49:40.035] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:49:40.035] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:49:40.041] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:49:40.041] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:49:40.041] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:49:40.052] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:49:40.052] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:49:40.067] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:49:40.067] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:49:40.091] INFO: PixTestCmd::init()
[15:49:40.108] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:49:40.108] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:49:40.108] INFO: readGainPedestalParameters data/mqa09/phCalibrationFitErr35_C0.dat .. data/mqa09/phCalibrationFitErr35_C15.dat
[15:49:40.354] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:49:40.354] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:49:40.374] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:49:40.382] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:49:40.382] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:49:40.382] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:49:40.407] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:49:40.407] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:49:40.407] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:49:40.407] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:49:40.407] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:49:40.415] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:49:40.415] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:49:40.437] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:49:40.437] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:49:40.438] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:49:40.438] INFO: readReadbackCal: data/mqa09/readbackCal_C0.dat .. data/mqa09/readbackCal_C15.dat
[15:49:40.451] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:49:40.459] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:49:40.459] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:49:40.459] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:49:40.459] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:49:40.475] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:49:40.475] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:49:48.384] INFO: ######################################################################
[15:49:48.384] INFO: PixTestAlive::doTest()
[15:49:48.384] INFO: ######################################################################
[15:49:48.388] INFO: ----------------------------------------------------------------------
[15:49:48.388] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:49:48.388] INFO: ----------------------------------------------------------------------
[15:49:48.390] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:49:48.768] INFO: Expecting 41600 events.
[15:49:53.109] INFO: 41600 events read in total (3623ms).
[15:49:53.255] INFO: Test took 4865ms.
[15:49:53.266] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:53.266] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:49:53.266] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:49:53.559] INFO: PixTestAlive::aliveTest() done
[15:49:53.559] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[15:49:53.559] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 52 20 44 67 69 79 79 63 75 66 78 62 64 41 37 25
[15:49:53.583] INFO: ----------------------------------------------------------------------
[15:49:53.584] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:49:53.584] INFO: ----------------------------------------------------------------------
[15:49:53.586] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:49:53.964] INFO: Expecting 41600 events.
[15:49:57.051] INFO: 41600 events read in total (2372ms).
[15:49:57.051] INFO: Test took 3465ms.
[15:49:57.051] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:57.051] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:49:57.051] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:49:57.052] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:49:57.474] INFO: PixTestAlive::maskTest() done
[15:49:57.474] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:49:57.498] INFO: ----------------------------------------------------------------------
[15:49:57.498] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:49:57.498] INFO: ----------------------------------------------------------------------
[15:49:57.501] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:49:57.877] INFO: Expecting 41600 events.
[15:50:02.259] INFO: 41600 events read in total (3667ms).
[15:50:02.261] INFO: Test took 4760ms.
[15:50:02.272] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:02.272] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:50:02.272] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:50:02.643] INFO: PixTestAlive::addressDecodingTest() done
[15:50:02.643] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:50:02.643] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:50:22.645] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:50:22.645] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[15:50:25.797] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:50:25.797] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:50:25.797] INFO: ----------------------------------------------------------------------
[15:50:25.797] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:50:25.797] INFO: ----------------------------------------------------------------------
[15:50:25.936] INFO: Expecting 768 events.
[15:50:27.070] INFO: 768 events read in total (419ms).
[15:50:27.070] INFO: Test took 1267ms.
[15:50:27.076] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:50:27.873] INFO: Expecting 41600 events.
[15:50:31.682] INFO: 41600 events read in total (3282ms).
[15:50:31.688] INFO: Test took 4612ms.
[15:50:31.834] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:31.834] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 852232
[15:50:31.834] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:50:31.835] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:50:31.896] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:50:32.561] INFO: Expecting 41600 events.
[15:50:36.504] INFO: 41600 events read in total (3416ms).
[15:50:36.510] INFO: Test took 4614ms.
[15:50:36.650] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:36.650] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 860983
[15:50:36.650] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:50:36.650] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:50:36.715] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:50:37.380] INFO: Expecting 41600 events.
[15:50:41.358] INFO: 41600 events read in total (3452ms).
[15:50:41.363] INFO: Test took 4648ms.
[15:50:41.504] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:41.504] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 863348
[15:50:41.504] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:50:41.504] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:50:41.569] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:50:42.235] INFO: Expecting 41600 events.
[15:50:46.224] INFO: 41600 events read in total (3463ms).
[15:50:46.231] INFO: Test took 4661ms.
[15:50:46.372] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:46.373] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 863657
[15:50:46.373] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:50:46.373] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:50:46.438] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:50:47.104] INFO: Expecting 41600 events.
[15:50:51.101] INFO: 41600 events read in total (3470ms).
[15:50:51.106] INFO: Test took 4668ms.
[15:50:51.247] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:51.247] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864647
[15:50:51.247] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:50:51.248] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:50:51.312] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:50:51.977] INFO: Expecting 41600 events.
[15:50:55.979] INFO: 41600 events read in total (3475ms).
[15:50:55.985] INFO: Test took 4673ms.
[15:50:56.126] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:56.126] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864409
[15:50:56.126] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:50:56.126] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:50:56.191] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:50:56.857] INFO: Expecting 41600 events.
[15:51:00.867] INFO: 41600 events read in total (3483ms).
[15:51:00.873] INFO: Test took 4681ms.
[15:51:01.013] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:01.013] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 866723
[15:51:01.013] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:51:01.014] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:51:01.079] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:51:01.744] INFO: Expecting 41600 events.
[15:51:05.759] INFO: 41600 events read in total (3488ms).
[15:51:05.764] INFO: Test took 4685ms.
[15:51:05.905] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:05.905] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 865520
[15:51:05.905] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:51:05.906] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:51:05.972] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:51:06.637] INFO: Expecting 41600 events.
[15:51:10.651] INFO: 41600 events read in total (3487ms).
[15:51:10.656] INFO: Test took 4684ms.
[15:51:10.799] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:10.799] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 865703
[15:51:10.799] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:51:10.800] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:51:10.865] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:51:11.530] INFO: Expecting 41600 events.
[15:51:15.548] INFO: 41600 events read in total (3491ms).
[15:51:15.553] INFO: Test took 4688ms.
[15:51:15.694] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:15.694] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 865029
[15:51:15.694] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:51:15.694] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:51:15.759] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:51:16.424] INFO: Expecting 41600 events.
[15:51:20.435] INFO: 41600 events read in total (3484ms).
[15:51:20.440] INFO: Test took 4681ms.
[15:51:20.580] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:20.580] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 865010
[15:51:20.580] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:51:20.581] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:51:20.646] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:51:21.312] INFO: Expecting 41600 events.
[15:51:25.327] INFO: 41600 events read in total (3489ms).
[15:51:25.333] INFO: Test took 4687ms.
[15:51:25.475] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:25.475] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 865121
[15:51:25.475] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:51:25.476] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:51:25.541] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:51:26.206] INFO: Expecting 41600 events.
[15:51:30.208] INFO: 41600 events read in total (3475ms).
[15:51:30.213] INFO: Test took 4672ms.
[15:51:30.352] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:30.352] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864675
[15:51:30.352] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:51:30.353] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:51:30.418] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:51:31.083] INFO: Expecting 41600 events.
[15:51:35.104] INFO: 41600 events read in total (3494ms).
[15:51:35.109] INFO: Test took 4691ms.
[15:51:35.250] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:35.250] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 865835
[15:51:35.250] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:51:35.250] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:51:35.316] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:51:35.981] INFO: Expecting 41600 events.
[15:51:39.993] INFO: 41600 events read in total (3485ms).
[15:51:39.998] INFO: Test took 4682ms.
[15:51:40.140] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:40.140] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864303
[15:51:40.140] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:51:40.141] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:51:40.207] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:51:40.873] INFO: Expecting 41600 events.
[15:51:44.902] INFO: 41600 events read in total (3502ms).
[15:51:44.908] INFO: Test took 4701ms.
[15:51:45.048] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:45.049] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 864297
[15:51:45.049] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:51:45.049] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:51:45.115] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:51:45.780] INFO: Expecting 41600 events.
[15:51:49.789] INFO: 41600 events read in total (3483ms).
[15:51:49.794] INFO: Test took 4679ms.
[15:51:49.935] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:49.936] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 865672
[15:51:49.936] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:51:49.936] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:51:50.001] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:51:50.666] INFO: Expecting 41600 events.
[15:51:54.680] INFO: 41600 events read in total (3487ms).
[15:51:54.685] INFO: Test took 4684ms.
[15:51:54.825] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:54.825] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 863733
[15:51:54.825] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:51:54.826] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:51:54.892] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:51:55.557] INFO: Expecting 41600 events.
[15:51:59.538] INFO: 41600 events read in total (3454ms).
[15:51:59.543] INFO: Test took 4651ms.
[15:51:59.685] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:59.685] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 863733
[15:51:59.685] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:51:59.685] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:51:59.752] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:52:00.417] INFO: Expecting 41600 events.
[15:52:04.248] INFO: 41600 events read in total (3305ms).
[15:52:04.254] INFO: Test took 4502ms.
[15:52:04.393] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:04.393] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 856300
[15:52:04.393] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:52:04.393] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:52:04.679] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 154 eff = 0.998702
[15:52:04.679] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 163 eff = 0.999087
[15:52:04.679] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 148 eff = 0.997813
[15:52:04.679] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 146 eff = 0.996875
[15:52:04.679] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 165 eff = 0.996226
[15:52:04.679] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 158 eff = 0.995962
[15:52:04.679] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 140 eff = 0.995553
[15:52:04.680] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 169 eff = 0.996322
[15:52:04.680] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 205 eff = 0.996538
[15:52:04.680] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 149 eff = 0.995793
[15:52:04.680] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 154 eff = 0.995745
[15:52:04.680] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 188 eff = 0.996034
[15:52:04.680] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 173 eff = 0.996418
[15:52:04.680] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 172 eff = 0.997716
[15:52:04.681] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 153 eff = 0.999399
[15:52:04.681] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 151 eff = 0.998654
[15:52:09.173] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:52:09.173] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:52:09.173] INFO: ----------------------------------------------------------------------
[15:52:09.173] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:52:09.173] INFO: ----------------------------------------------------------------------
[15:52:09.173] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:52:09.173] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:52:09.173] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:52:09.173] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:52:09.173] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:52:09.173] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:52:09.173] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:52:09.173] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:52:09.173] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:52:09.173] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:52:09.173] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:52:09.173] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:52:09.191] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:52:09.862] INFO: Expecting 208000 events.
[15:52:26.454] INFO: 208000 events read in total (16067ms).
[15:52:26.474] INFO: Test took 17283ms.
[15:52:27.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:27.244] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4011180
[15:52:27.244] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:52:27.244] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:52:27.751] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[15:52:27.751] INFO: number of red-efficiency pixels: 326 233 420 656 736 788 771 705 710 829 745 710 699 444 191 216
[15:52:27.751] INFO: number of X-ray hits detected: 175218 116967 196403 289419 307407 308568 301601 289400 300397 313561 307623 294184 292071 193324 116680 141798
[15:52:27.751] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:52:27.751] INFO: number of Vcal hits detected: 207658 207758 207523 207270 207174 207038 207116 207202 207165 207021 207131 207128 207215 207514 207799 207732
[15:52:27.751] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.6 99.6 99.6 99.6 99.6 99.6 99.6 99.6 99.6 99.8 99.9 99.9
[15:52:27.751] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.6 99.6 99.5 99.6 99.6 99.6 99.5 99.6 99.6 99.6 99.8 99.9 99.9
[15:52:27.751] INFO: X-ray hit rate [MHz/cm2]: 51.4 34.3 57.6 84.8 90.1 90.4 88.4 84.8 88.0 91.9 90.2 86.2 85.6 56.7 34.2 41.6
[15:52:27.751] INFO: PixTestHighRate::doXPixelAlive() done
[15:52:27.799] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:52:27.799] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:52:27.799] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:52:27.799] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:52:27.799] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:52:27.799] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:52:27.799] INFO: PixTest:: pg_setup set to default.
[15:52:32.221] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:52:32.221] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[15:52:53.965] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[15:52:53.965] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[15:52:53.965] INFO: ----------------------------------------------------------------------
[15:52:53.965] INFO: PixTestHighRate::runDaq() running for 2 seconds
[15:52:53.965] INFO: ----------------------------------------------------------------------
[15:52:54.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[15:52:54.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[15:52:54.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[15:52:54.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[15:52:54.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[15:52:54.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[15:52:54.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[15:52:54.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[15:52:54.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[15:52:54.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[15:52:54.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[15:52:54.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[15:52:54.957] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[15:52:56.960] INFO: data taking finished, elapsed time: 2 seconds.
[15:52:58.886] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39827 events with 752821 pixels
[15:52:58.891] INFO: PixTest:: pg_setup set to default.
[15:52:58.999] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[15:52:58.999] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[15:52:58.999] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[15:52:58.999] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[15:52:59.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[15:52:59.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[15:52:59.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[15:52:59.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[15:52:59.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[15:52:59.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[15:52:59.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[15:52:59.000] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[15:52:59.001] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[15:52:59.001] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[15:52:59.001] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[15:52:59.001] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[15:52:59.001] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[15:52:59.001] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[15:52:59.001] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[15:52:59.001] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[15:52:59.001] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[15:52:59.001] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[15:52:59.001] INFO: Pixels without X-ray hits (per ROC): 315 826 436 73 86 89 96 76 96 83 101 166 90 484 890 447
[15:52:59.001] INFO: PixTestHighRate::doRunDaq() done
[15:53:16.677] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:53:16.677] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C13_V0 -> hitMap_daqbbtest_mod
[15:53:18.189] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:53:18.190] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 391.5mA
[15:53:18.190] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[15:53:18.190] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[15:53:18.190] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:53:18.190] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:53:18.191] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:53:18.191] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:53:18.191] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:53:18.191] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:53:18.191] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:53:18.191] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:53:18.191] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:53:18.191] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:53:18.298] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:53:18.298] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:53:18.311] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:53:18.311] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:53:18.311] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:53:18.311] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:53:18.311] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:53:18.311] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:53:18.314] QUIET: Connection to board 58 closed.
[15:53:18.394] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries