[12:40:58.761] INFO: *** Welcome to pxar ***
[12:40:58.761] INFO: *** Today: 2016/08/26
[12:40:58.786] INFO: *** Version: v1.9.0-818-g96727
[12:40:58.786] INFO: readRocDacs: data/mq817_300/dacParameters35_C0.dat .. data/mq817_300/dacParameters35_C15.dat
[12:40:58.787] INFO: readTbmDacs: data/mq817_300/tbmParameters_C0a.dat .. data/mq817_300/tbmParameters_C0b.dat
[12:40:58.787] INFO: readMaskFile: data/mq817_300/defaultMaskFile.dat
[12:40:58.787] INFO: readTrimFile: data/mq817_300/trimParameters35_C0.dat .. data/mq817_300/trimParameters35_C15.dat
[12:40:58.936] INFO: clk: 4
[12:40:58.936] INFO: ctr: 4
[12:40:58.936] INFO: sda: 19
[12:40:58.936] INFO: tin: 9
[12:40:58.936] INFO: level: 15
[12:40:58.936] INFO: triggerdelay: 0
[12:40:58.936] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:40:58.936] INFO: Log level: DEBUG
[12:40:58.946] QUIET: Connection to board DTB_WRPRHI opened.
[12:40:58.950] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[12:40:58.952] INFO: RPC call hashes of host and DTB match: 398089610
[12:41:00.556] INFO: DUT info:
[12:41:00.556] INFO: The DUT currently contains the following objects:
[12:41:00.556] INFO: 2 TBM Cores tbm08c (2 ON)
[12:41:00.556] INFO: TBM Core alpha (0): 7 registers set
[12:41:00.556] INFO: TBM Core beta (1): 7 registers set
[12:41:00.556] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:41:00.556] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.556] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.556] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.556] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.556] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.556] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.556] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.556] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.556] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.556] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.556] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.556] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.557] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.557] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.557] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.557] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:41:00.557] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:41:00.558] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/ttyS0
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> ignoreproblems: checkbox(0)
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:41:00.559] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10000
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> ignorereadback: checkbox(0)
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:41:00.560] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:41:00.562] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32874496
[12:41:00.562] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x271e330
[12:41:00.562] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x26ac370
[12:41:00.562] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f35c5d94010
[12:41:00.562] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f35cbfff510
[12:41:00.562] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32882688 fPxarMemory = 0x7f35c5d94010
[12:41:00.564] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 384.3mA
[12:41:00.565] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[12:41:00.565] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[12:41:01.059] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2cf90b0
[12:41:01.123] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:41:01.123] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:41:01.124] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:41:01.131] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:41:01.131] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:41:01.131] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:41:01.144] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:41:01.144] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:41:01.153] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:41:01.153] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:41:01.176] INFO: PixTestCmd::init()
[12:41:01.192] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:41:01.192] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:41:01.192] INFO: readGainPedestalParameters data/mq817_300/phCalibrationFitErr35_C0.dat .. data/mq817_300/phCalibrationFitErr35_C15.dat
[12:41:01.439] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:41:01.439] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:41:01.453] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:41:01.459] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:41:01.459] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:41:01.459] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:41:01.482] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:41:01.482] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:41:01.482] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:41:01.482] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:41:01.482] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:41:01.490] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:41:01.490] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:41:01.512] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:41:01.512] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:41:01.512] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:41:01.512] INFO: readReadbackCal: data/mq817_300/readbackCal_C0.dat .. data/mq817_300/readbackCal_C15.dat
[12:41:01.524] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:41:01.534] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:41:01.534] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10000
[12:41:01.534] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:41:01.534] DEBUG: <PixTestTiming.cc/setParameter:L54> fIgnoreReadBack: 0
[12:41:01.534] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:41:01.551] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:41:01.551] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:41:12.776] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[12:41:14.496] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[12:41:14.496] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[12:41:14.496] INFO: ----------------------------------------------------------------------
[12:41:14.496] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[12:41:14.496] INFO: ----------------------------------------------------------------------
[12:41:15.470] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[12:41:28.180] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[12:41:28.183] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:41:55.626] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1252285 events.
[12:42:01.367] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1252285, pixels seen in all events: 5224304
[12:42:01.472] INFO: Resuming triggers.
[12:42:14.188] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[12:42:14.225] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:42:41.622] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1252749 events.
[12:42:47.368] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1252749, pixels seen in all events: 5218012
[12:42:47.469] INFO: Resuming triggers.
[12:43:00.198] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[12:43:00.234] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:43:27.499] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1253970 events.
[12:43:33.147] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1253970, pixels seen in all events: 5203174
[12:43:33.383] INFO: Resuming triggers.
[12:43:46.111] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[12:43:46.149] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:44:13.410] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1253920 events.
[12:44:19.057] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1253920, pixels seen in all events: 5203726
[12:44:19.298] INFO: Resuming triggers.
[12:44:28.490] INFO: data taking finished, elapsed time: 60 seconds.
[12:44:28.686] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:44:48.403] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 905464 events.
[12:44:52.562] DEBUG: <PixTestXray.cc/processData:L823> # events read: 905464, pixels seen in all events: 3752419
[12:44:52.644] INFO: PixTest:: pg_setup set to default.
[12:44:52.733] INFO: PixTestXray::doPhRun() done
[12:45:00.320] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:45:00.321] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[12:45:00.321] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[12:45:00.321] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[12:45:00.321] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:45:00.321] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:45:00.322] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:45:00.322] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:45:00.322] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:45:00.322] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:45:00.322] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:45:00.322] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:45:00.322] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:45:00.322] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:45:00.322] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:45:00.322] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:45:00.322] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:45:00.322] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:45:00.322] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:45:00.322] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:45:00.322] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:45:00.322] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:45:00.595] QUIET: Connection to board 58 closed.
[12:45:00.675] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[12:34:38.945] INFO: *** Welcome to pxar ***
[12:34:38.945] INFO: *** Today: 2016/08/26
[12:34:38.969] INFO: *** Version: v1.9.0-818-g96727
[12:34:38.969] INFO: readRocDacs: data/mq817_300/dacParameters35_C0.dat .. data/mq817_300/dacParameters35_C15.dat
[12:34:38.970] INFO: readTbmDacs: data/mq817_300/tbmParameters_C0a.dat .. data/mq817_300/tbmParameters_C0b.dat
[12:34:38.970] INFO: readMaskFile: data/mq817_300/defaultMaskFile.dat
[12:34:38.970] INFO: readTrimFile: data/mq817_300/trimParameters35_C0.dat .. data/mq817_300/trimParameters35_C15.dat
[12:34:39.120] INFO: clk: 4
[12:34:39.120] INFO: ctr: 4
[12:34:39.120] INFO: sda: 19
[12:34:39.120] INFO: tin: 9
[12:34:39.120] INFO: level: 15
[12:34:39.120] INFO: triggerdelay: 0
[12:34:39.120] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:34:39.120] INFO: Log level: DEBUG
[12:34:39.131] QUIET: Connection to board DTB_WRPRHI opened.
[12:34:39.134] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[12:34:39.136] INFO: RPC call hashes of host and DTB match: 398089610
[12:34:40.739] INFO: DUT info:
[12:34:40.739] INFO: The DUT currently contains the following objects:
[12:34:40.739] INFO: 2 TBM Cores tbm08c (2 ON)
[12:34:40.739] INFO: TBM Core alpha (0): 7 registers set
[12:34:40.739] INFO: TBM Core beta (1): 7 registers set
[12:34:40.739] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:34:40.739] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.739] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.739] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.739] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.739] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.739] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.739] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.739] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.739] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.739] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.739] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.739] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.739] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.739] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.739] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.739] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:34:40.740] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/ttyS0
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:34:40.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> ignoreproblems: checkbox(0)
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10000
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> ignorereadback: checkbox(0)
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:34:40.742] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:34:40.743] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:34:40.745] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32735232
[12:34:40.745] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x29d3330
[12:34:40.745] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2961370
[12:34:40.745] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fd4f1d94010
[12:34:40.745] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fd4f7fff510
[12:34:40.745] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32743424 fPxarMemory = 0x7fd4f1d94010
[12:34:40.746] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 384.3mA
[12:34:40.748] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[12:34:40.748] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[12:34:41.246] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2fae170
[12:34:41.312] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:34:41.312] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:34:41.313] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:34:41.320] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:34:41.320] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:34:41.320] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:34:41.333] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:34:41.333] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:34:41.343] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:34:41.343] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:34:41.364] INFO: PixTestCmd::init()
[12:34:41.373] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:34:41.373] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:34:41.373] INFO: readGainPedestalParameters data/mq817_300/phCalibrationFitErr35_C0.dat .. data/mq817_300/phCalibrationFitErr35_C15.dat
[12:34:41.627] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:34:41.627] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:34:41.640] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:34:41.646] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:34:41.646] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:34:41.646] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:34:41.667] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:34:41.667] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:34:41.667] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:34:41.667] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:34:41.667] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:34:41.674] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:34:41.674] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:34:41.688] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:34:41.688] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:34:41.688] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:34:41.688] INFO: readReadbackCal: data/mq817_300/readbackCal_C0.dat .. data/mq817_300/readbackCal_C15.dat
[12:34:41.699] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:34:41.706] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:34:41.706] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10000
[12:34:41.706] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:34:41.706] DEBUG: <PixTestTiming.cc/setParameter:L54> fIgnoreReadBack: 0
[12:34:41.706] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:34:41.725] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:34:41.725] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:34:51.543] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[12:34:52.743] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[12:34:52.743] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[12:34:52.743] INFO: ----------------------------------------------------------------------
[12:34:52.743] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[12:34:52.743] INFO: ----------------------------------------------------------------------
[12:34:53.717] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[12:35:01.472] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[12:35:01.476] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:35:27.152] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 764064 events.
[12:35:39.116] DEBUG: <PixTestXray.cc/processData:L823> # events read: 764064, pixels seen in all events: 11084228
[12:35:39.369] INFO: Resuming triggers.
[12:35:47.129] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[12:35:47.167] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:36:12.767] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 764456 events.
[12:36:24.785] DEBUG: <PixTestXray.cc/processData:L823> # events read: 764456, pixels seen in all events: 11078567
[12:36:24.978] INFO: Resuming triggers.
[12:36:32.755] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:36:32.789] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:36:58.408] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 766071 events.
[12:37:10.403] DEBUG: <PixTestXray.cc/processData:L823> # events read: 766071, pixels seen in all events: 11102154
[12:37:10.570] INFO: Resuming triggers.
[12:37:18.333] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[12:37:18.431] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:37:44.058] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 764778 events.
[12:37:56.023] DEBUG: <PixTestXray.cc/processData:L823> # events read: 764778, pixels seen in all events: 11074532
[12:37:56.258] INFO: Resuming triggers.
[12:38:04.024] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[12:38:04.061] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:38:29.556] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 765087 events.
[12:38:41.511] DEBUG: <PixTestXray.cc/processData:L823> # events read: 765087, pixels seen in all events: 11070380
[12:38:41.746] INFO: Resuming triggers.
[12:38:49.512] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[12:38:49.549] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:39:15.160] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 765121 events.
[12:39:27.113] DEBUG: <PixTestXray.cc/processData:L823> # events read: 765121, pixels seen in all events: 11070827
[12:39:27.346] INFO: Resuming triggers.
[12:39:35.108] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[12:39:35.145] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:40:00.718] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 764676 events.
[12:40:12.688] DEBUG: <PixTestXray.cc/processData:L823> # events read: 764676, pixels seen in all events: 11075685
[12:40:12.925] INFO: Resuming triggers.
[12:40:18.652] INFO: data taking finished, elapsed time: 60 seconds.
[12:40:18.848] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:40:37.795] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 564163 events.
[12:40:46.654] DEBUG: <PixTestXray.cc/processData:L823> # events read: 564163, pixels seen in all events: 8157615
[12:40:46.841] INFO: PixTest:: pg_setup set to default.
[12:40:46.879] INFO: PixTestXray::doPhRun() done
[12:40:52.096] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:40:52.096] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[12:40:52.097] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[12:40:52.097] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[12:40:52.097] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:40:52.097] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:40:52.097] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:40:52.097] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:40:52.097] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:40:52.098] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:40:52.098] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:40:52.098] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:40:52.098] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:40:52.098] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:40:52.098] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:40:52.098] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:40:52.098] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:40:52.098] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:40:52.098] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:40:52.098] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:40:52.098] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:40:52.098] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:40:52.407] QUIET: Connection to board 58 closed.
[12:40:52.487] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[12:29:26.739] INFO: *** Welcome to pxar ***
[12:29:26.739] INFO: *** Today: 2016/08/26
[12:29:26.764] INFO: *** Version: v1.9.0-818-g96727
[12:29:26.764] INFO: readRocDacs: data/mq817_300/dacParameters35_C0.dat .. data/mq817_300/dacParameters35_C15.dat
[12:29:26.765] INFO: readTbmDacs: data/mq817_300/tbmParameters_C0a.dat .. data/mq817_300/tbmParameters_C0b.dat
[12:29:26.765] INFO: readMaskFile: data/mq817_300/defaultMaskFile.dat
[12:29:26.766] INFO: readTrimFile: data/mq817_300/trimParameters35_C0.dat .. data/mq817_300/trimParameters35_C15.dat
[12:29:26.914] INFO: clk: 4
[12:29:26.914] INFO: ctr: 4
[12:29:26.914] INFO: sda: 19
[12:29:26.914] INFO: tin: 9
[12:29:26.914] INFO: level: 15
[12:29:26.914] INFO: triggerdelay: 0
[12:29:26.914] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:29:26.914] INFO: Log level: DEBUG
[12:29:26.926] QUIET: Connection to board DTB_WRPRHI opened.
[12:29:26.929] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[12:29:26.932] INFO: RPC call hashes of host and DTB match: 398089610
[12:29:28.535] INFO: DUT info:
[12:29:28.535] INFO: The DUT currently contains the following objects:
[12:29:28.535] INFO: 2 TBM Cores tbm08c (2 ON)
[12:29:28.535] INFO: TBM Core alpha (0): 7 registers set
[12:29:28.535] INFO: TBM Core beta (1): 7 registers set
[12:29:28.535] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:29:28.535] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.535] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.535] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.535] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.535] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.535] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.535] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.535] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.535] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.535] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.535] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.535] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.535] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.535] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.536] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.536] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:29:28.536] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:29:28.537] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/ttyS0
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> ignoreproblems: checkbox(0)
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:29:28.538] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10000
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> ignorereadback: checkbox(0)
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:29:28.539] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:29:28.541] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33447936
[12:29:28.541] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1de5330
[12:29:28.541] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1d73370
[12:29:28.541] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fc057f91010
[12:29:28.541] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fc05e1fc510
[12:29:28.541] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33456128 fPxarMemory = 0x7fc057f91010
[12:29:28.543] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 384.3mA
[12:29:28.544] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[12:29:28.544] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[12:29:29.040] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x23c0180
[12:29:29.100] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:29:29.100] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:29:29.101] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:29:29.106] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:29:29.106] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:29:29.106] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:29:29.119] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:29:29.119] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:29:29.143] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:29:29.143] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:29:29.159] INFO: PixTestCmd::init()
[12:29:29.170] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:29:29.170] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:29:29.170] INFO: readGainPedestalParameters data/mq817_300/phCalibrationFitErr35_C0.dat .. data/mq817_300/phCalibrationFitErr35_C15.dat
[12:29:29.416] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:29:29.417] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:29:29.431] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:29:29.437] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:29:29.437] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:29:29.437] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:29:29.457] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:29:29.457] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:29:29.458] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:29:29.458] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:29:29.458] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:29:29.464] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:29:29.464] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:29:29.482] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:29:29.482] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:29:29.482] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:29:29.482] INFO: readReadbackCal: data/mq817_300/readbackCal_C0.dat .. data/mq817_300/readbackCal_C15.dat
[12:29:29.491] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:29:29.499] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:29:29.499] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10000
[12:29:29.500] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:29:29.500] DEBUG: <PixTestTiming.cc/setParameter:L54> fIgnoreReadBack: 0
[12:29:29.500] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:29:29.518] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:29:29.518] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:29:34.218] INFO: ######################################################################
[12:29:34.219] INFO: PixTestAlive::doTest()
[12:29:34.219] INFO: ######################################################################
[12:29:34.222] INFO: ----------------------------------------------------------------------
[12:29:34.222] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:29:34.222] INFO: ----------------------------------------------------------------------
[12:29:34.224] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:34.604] INFO: Expecting 41600 events.
[12:29:39.002] INFO: 41600 events read in total (3680ms).
[12:29:39.149] INFO: Test took 4924ms.
[12:29:39.159] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:39.159] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[12:29:39.159] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[12:29:39.436] INFO: PixTestAlive::aliveTest() done
[12:29:39.436] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:29:39.436] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 31 7 33 47 39 31 44 34 37 47 39 39 31 23 16 26
[12:29:39.464] INFO: ----------------------------------------------------------------------
[12:29:39.464] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:29:39.464] INFO: ----------------------------------------------------------------------
[12:29:39.467] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:39.847] INFO: Expecting 41600 events.
[12:29:42.966] INFO: 41600 events read in total (2404ms).
[12:29:42.966] INFO: Test took 3499ms.
[12:29:42.966] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:42.966] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[12:29:42.966] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[12:29:42.967] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:29:43.390] INFO: PixTestAlive::maskTest() done
[12:29:43.390] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:29:43.410] INFO: ----------------------------------------------------------------------
[12:29:43.410] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:29:43.410] INFO: ----------------------------------------------------------------------
[12:29:43.412] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:43.791] INFO: Expecting 41600 events.
[12:29:48.168] INFO: 41600 events read in total (3662ms).
[12:29:48.169] INFO: Test took 4757ms.
[12:29:48.181] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:48.181] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[12:29:48.181] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[12:29:48.551] INFO: PixTestAlive::addressDecodingTest() done
[12:29:48.551] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:29:48.552] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[12:29:52.495] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:29:52.496] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[12:29:55.031] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[12:29:55.031] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[12:29:55.031] INFO: ----------------------------------------------------------------------
[12:29:55.031] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:29:55.031] INFO: ----------------------------------------------------------------------
[12:29:55.171] INFO: Expecting 768 events.
[12:29:56.305] INFO: 768 events read in total (419ms).
[12:29:56.305] INFO: Test took 1268ms.
[12:29:56.311] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:29:57.108] INFO: Expecting 41600 events.
[12:30:00.471] INFO: 41600 events read in total (2836ms).
[12:30:00.475] INFO: Test took 4164ms.
[12:30:00.555] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:00.555] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 457307
[12:30:00.555] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[12:30:00.555] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:00.593] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:01.258] INFO: Expecting 41600 events.
[12:30:04.723] INFO: 41600 events read in total (2939ms).
[12:30:04.727] INFO: Test took 4134ms.
[12:30:04.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:04.803] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 462038
[12:30:04.803] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[12:30:04.803] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:04.842] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:05.507] INFO: Expecting 41600 events.
[12:30:08.999] INFO: 41600 events read in total (2966ms).
[12:30:09.003] INFO: Test took 4161ms.
[12:30:09.080] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:09.080] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 463637
[12:30:09.080] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[12:30:09.080] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:09.119] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:09.784] INFO: Expecting 41600 events.
[12:30:13.206] INFO: 41600 events read in total (2896ms).
[12:30:13.210] INFO: Test took 4091ms.
[12:30:13.287] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:13.287] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464185
[12:30:13.287] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[12:30:13.288] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:13.328] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:13.995] INFO: Expecting 41600 events.
[12:30:17.475] INFO: 41600 events read in total (2954ms).
[12:30:17.479] INFO: Test took 4151ms.
[12:30:17.556] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:17.556] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464866
[12:30:17.556] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[12:30:17.557] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:17.596] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:18.262] INFO: Expecting 41600 events.
[12:30:21.758] INFO: 41600 events read in total (2969ms).
[12:30:21.761] INFO: Test took 4165ms.
[12:30:21.839] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:21.839] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 463981
[12:30:21.839] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[12:30:21.839] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:21.879] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:22.545] INFO: Expecting 41600 events.
[12:30:26.055] INFO: 41600 events read in total (2984ms).
[12:30:26.058] INFO: Test took 4179ms.
[12:30:26.136] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:26.136] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464642
[12:30:26.136] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[12:30:26.137] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:26.177] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:26.843] INFO: Expecting 41600 events.
[12:30:30.346] INFO: 41600 events read in total (2977ms).
[12:30:30.350] INFO: Test took 4173ms.
[12:30:30.426] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:30.426] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465073
[12:30:30.426] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[12:30:30.427] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:30.465] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:31.130] INFO: Expecting 41600 events.
[12:30:34.635] INFO: 41600 events read in total (2978ms).
[12:30:34.639] INFO: Test took 4174ms.
[12:30:34.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:34.714] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464978
[12:30:34.714] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[12:30:34.715] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:34.754] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:35.419] INFO: Expecting 41600 events.
[12:30:38.923] INFO: 41600 events read in total (2978ms).
[12:30:38.926] INFO: Test took 4172ms.
[12:30:39.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:39.002] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 463327
[12:30:39.002] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[12:30:39.003] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:39.042] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:39.707] INFO: Expecting 41600 events.
[12:30:43.208] INFO: 41600 events read in total (2974ms).
[12:30:43.212] INFO: Test took 4170ms.
[12:30:43.287] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:43.287] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 463650
[12:30:43.287] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[12:30:43.288] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:43.326] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:43.992] INFO: Expecting 41600 events.
[12:30:47.497] INFO: 41600 events read in total (2979ms).
[12:30:47.500] INFO: Test took 4174ms.
[12:30:47.577] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:47.577] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464515
[12:30:47.577] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[12:30:47.577] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:47.616] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:48.281] INFO: Expecting 41600 events.
[12:30:51.792] INFO: 41600 events read in total (2984ms).
[12:30:51.795] INFO: Test took 4179ms.
[12:30:51.872] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:51.872] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465209
[12:30:51.872] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[12:30:51.873] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:51.913] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:52.579] INFO: Expecting 41600 events.
[12:30:56.074] INFO: 41600 events read in total (2969ms).
[12:30:56.077] INFO: Test took 4164ms.
[12:30:56.154] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:56.154] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 463950
[12:30:56.154] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[12:30:56.154] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:30:56.194] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:30:56.859] INFO: Expecting 41600 events.
[12:31:00.352] INFO: 41600 events read in total (2967ms).
[12:31:00.356] INFO: Test took 4162ms.
[12:31:00.438] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:31:00.438] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464478
[12:31:00.438] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[12:31:00.439] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:31:00.480] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:31:01.147] INFO: Expecting 41600 events.
[12:31:04.650] INFO: 41600 events read in total (2977ms).
[12:31:04.654] INFO: Test took 4174ms.
[12:31:04.732] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:31:04.732] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464456
[12:31:04.732] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[12:31:04.732] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:31:04.771] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:31:05.436] INFO: Expecting 41600 events.
[12:31:08.961] INFO: 41600 events read in total (2998ms).
[12:31:08.964] INFO: Test took 4193ms.
[12:31:09.042] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:31:09.042] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465446
[12:31:09.042] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[12:31:09.043] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:31:09.082] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:31:09.747] INFO: Expecting 41600 events.
[12:31:13.238] INFO: 41600 events read in total (2965ms).
[12:31:13.241] INFO: Test took 4159ms.
[12:31:13.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:31:13.319] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 463274
[12:31:13.319] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[12:31:13.319] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:31:13.358] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:31:14.023] INFO: Expecting 41600 events.
[12:31:17.487] INFO: 41600 events read in total (2937ms).
[12:31:17.491] INFO: Test took 4133ms.
[12:31:17.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:31:17.568] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 461934
[12:31:17.568] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[12:31:17.568] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:31:17.608] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:31:18.274] INFO: Expecting 41600 events.
[12:31:21.566] INFO: 41600 events read in total (2765ms).
[12:31:21.569] INFO: Test took 3961ms.
[12:31:21.644] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:31:21.645] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 454717
[12:31:21.645] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[12:31:21.645] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:31:21.904] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 170 eff = 0.999591
[12:31:21.904] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 147 eff = 0.999736
[12:31:21.904] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 176 eff = 0.999447
[12:31:21.905] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 144 eff = 0.999207
[12:31:21.905] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 147 eff = 0.998942
[12:31:21.905] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 159 eff = 0.99899
[12:31:21.905] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 166 eff = 0.999111
[12:31:21.906] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 143 eff = 0.998942
[12:31:21.906] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 136 eff = 0.998966
[12:31:21.906] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 164 eff = 0.998942
[12:31:21.906] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 133 eff = 0.998774
[12:31:21.907] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 151 eff = 0.998918
[12:31:21.907] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 168 eff = 0.998966
[12:31:21.907] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 145 eff = 0.999351
[12:31:21.907] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 187 eff = 0.99976
[12:31:21.908] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 155 eff = 0.999639
[12:31:25.071] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[12:31:25.071] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[12:31:25.071] INFO: ----------------------------------------------------------------------
[12:31:25.071] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:31:25.071] INFO: ----------------------------------------------------------------------
[12:31:25.072] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[12:31:25.072] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[12:31:25.072] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[12:31:25.072] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[12:31:25.072] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[12:31:25.072] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[12:31:25.072] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[12:31:25.072] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[12:31:25.072] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[12:31:25.072] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[12:31:25.072] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[12:31:25.072] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[12:31:25.090] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:31:25.759] INFO: Expecting 208000 events.
[12:31:39.275] INFO: 208000 events read in total (12990ms).
[12:31:39.287] INFO: Test took 14197ms.
[12:31:39.642] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:31:39.642] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2042668
[12:31:39.642] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[12:31:39.643] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:31:40.013] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:31:40.013] INFO: number of red-efficiency pixels: 134 79 119 239 261 233 244 219 266 220 262 226 254 155 58 89
[12:31:40.013] INFO: number of X-ray hits detected: 88676 59561 98874 144166 156905 156328 155082 137816 146816 158424 157366 145180 147747 98179 55440 69548
[12:31:40.013] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:31:40.013] INFO: number of Vcal hits detected: 207859 207920 207877 207746 207731 207761 207742 207770 207720 207774 207726 207763 207735 207840 207941 207909
[12:31:40.013] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[12:31:40.013] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[12:31:40.013] INFO: X-ray hit rate [MHz/cm2]: 26.0 17.5 29.0 42.3 46.0 45.8 45.5 40.4 43.0 46.4 46.1 42.6 43.3 28.8 16.2 20.4
[12:31:40.013] INFO: PixTestHighRate::doXPixelAlive() done
[12:31:40.058] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[12:31:40.058] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[12:31:40.058] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[12:31:40.058] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[12:31:40.058] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[12:31:40.058] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[12:31:40.058] INFO: PixTest:: pg_setup set to default.
[12:31:44.839] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:31:44.840] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[12:31:47.119] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[12:31:47.120] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[12:31:47.120] INFO: ----------------------------------------------------------------------
[12:31:47.120] INFO: PixTestHighRate::runDaq() running for 2 seconds
[12:31:47.120] INFO: ----------------------------------------------------------------------
[12:31:47.323] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[12:31:47.324] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[12:31:47.324] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[12:31:47.324] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[12:31:47.324] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[12:31:47.324] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[12:31:47.324] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[12:31:47.324] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[12:31:47.324] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[12:31:47.324] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[12:31:47.324] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[12:31:47.324] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[12:31:48.281] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[12:31:50.284] INFO: data taking finished, elapsed time: 2 seconds.
[12:31:51.781] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39827 events with 374953 pixels
[12:31:51.787] INFO: PixTest:: pg_setup set to default.
[12:31:51.928] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[12:31:51.928] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[12:31:51.928] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[12:31:51.928] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[12:31:51.929] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[12:31:51.929] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[12:31:51.929] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[12:31:51.929] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[12:31:51.929] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[12:31:51.929] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[12:31:51.929] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[12:31:51.930] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[12:31:51.930] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[12:31:51.930] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[12:31:51.930] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[12:31:51.930] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[12:31:51.930] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[12:31:51.930] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[12:31:51.930] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[12:31:51.930] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[12:31:51.930] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[12:31:51.930] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[12:31:51.931] INFO: Pixels without X-ray hits (per ROC): 754 1353 805 228 202 237 246 200 200 216 241 358 230 776 1468 926
[12:31:51.931] INFO: PixTestHighRate::doRunDaq() done
[12:31:56.351] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:31:56.351] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C12_V0 -> hitMap_daqbbtest_mod
[12:31:58.519] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:31:58.520] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.1mA
[12:31:58.521] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[12:31:58.521] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[12:31:58.521] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:31:58.521] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:31:58.521] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:31:58.521] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:31:58.521] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:31:58.521] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:31:58.521] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:31:58.522] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:31:58.522] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:31:58.522] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:31:58.624] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:31:58.624] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:31:58.638] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:31:58.638] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:31:58.638] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:31:58.638] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:31:58.638] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:31:58.638] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:31:58.641] QUIET: Connection to board 58 closed.
[12:31:58.720] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[12:26:37.132] INFO: *** Welcome to pxar ***
[12:26:37.132] INFO: *** Today: 2016/08/26
[12:26:37.156] INFO: *** Version: v1.9.0-818-g96727
[12:26:37.156] INFO: readRocDacs: data/mq817_300/dacParameters35_C0.dat .. data/mq817_300/dacParameters35_C15.dat
[12:26:37.157] INFO: readTbmDacs: data/mq817_300/tbmParameters_C0a.dat .. data/mq817_300/tbmParameters_C0b.dat
[12:26:37.157] INFO: readMaskFile: data/mq817_300/defaultMaskFile.dat
[12:26:37.157] INFO: readTrimFile: data/mq817_300/trimParameters35_C0.dat .. data/mq817_300/trimParameters35_C15.dat
[12:26:37.307] INFO: clk: 4
[12:26:37.307] INFO: ctr: 4
[12:26:37.307] INFO: sda: 19
[12:26:37.307] INFO: tin: 9
[12:26:37.307] INFO: level: 15
[12:26:37.307] INFO: triggerdelay: 0
[12:26:37.307] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:26:37.307] INFO: Log level: DEBUG
[12:26:37.318] QUIET: Connection to board DTB_WRPRHI opened.
[12:26:37.321] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[12:26:37.324] INFO: RPC call hashes of host and DTB match: 398089610
[12:26:38.926] INFO: DUT info:
[12:26:38.926] INFO: The DUT currently contains the following objects:
[12:26:38.926] INFO: 2 TBM Cores tbm08c (2 ON)
[12:26:38.926] INFO: TBM Core alpha (0): 7 registers set
[12:26:38.926] INFO: TBM Core beta (1): 7 registers set
[12:26:38.926] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:26:38.927] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:26:38.927] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:26:38.927] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:26:38.927] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:26:38.927] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:26:38.927] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:26:38.927] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:26:38.927] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:26:38.927] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:26:38.928] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/ttyS0
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> ignoreproblems: checkbox(0)
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:26:38.929] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10000
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> ignorereadback: checkbox(0)
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:26:38.930] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:26:38.933] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32915456
[12:26:38.933] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x25f0330
[12:26:38.933] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x257e370
[12:26:38.933] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f8fc9d94010
[12:26:38.933] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f8fcffff510
[12:26:38.933] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32923648 fPxarMemory = 0x7f8fc9d94010
[12:26:38.934] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[12:26:38.935] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[12:26:38.935] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[12:26:39.434] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2bcb170
[12:26:39.498] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:26:39.498] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:26:39.498] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:26:39.504] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:26:39.504] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:26:39.504] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:26:39.516] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:26:39.516] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:26:39.533] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:26:39.534] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:26:39.551] INFO: PixTestCmd::init()
[12:26:39.571] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:26:39.571] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:26:39.571] INFO: readGainPedestalParameters data/mq817_300/phCalibrationFitErr35_C0.dat .. data/mq817_300/phCalibrationFitErr35_C15.dat
[12:26:39.818] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:26:39.818] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:26:39.832] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:26:39.838] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:26:39.838] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:26:39.838] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:26:39.860] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:26:39.860] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:26:39.860] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:26:39.860] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:26:39.860] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:26:39.868] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:26:39.868] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:26:39.891] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:26:39.891] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:26:39.891] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:26:39.891] INFO: readReadbackCal: data/mq817_300/readbackCal_C0.dat .. data/mq817_300/readbackCal_C15.dat
[12:26:39.901] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:26:39.910] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:26:39.910] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10000
[12:26:39.910] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:26:39.910] DEBUG: <PixTestTiming.cc/setParameter:L54> fIgnoreReadBack: 0
[12:26:39.910] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:26:39.925] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:26:39.925] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:26:44.770] INFO: ######################################################################
[12:26:44.771] INFO: PixTestAlive::doTest()
[12:26:44.771] INFO: ######################################################################
[12:26:44.774] INFO: ----------------------------------------------------------------------
[12:26:44.774] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:26:44.774] INFO: ----------------------------------------------------------------------
[12:26:44.776] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:26:45.156] INFO: Expecting 41600 events.
[12:26:49.566] INFO: 41600 events read in total (3692ms).
[12:26:49.713] INFO: Test took 4937ms.
[12:26:49.726] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:49.726] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[12:26:49.726] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[12:26:50.003] INFO: PixTestAlive::aliveTest() done
[12:26:50.003] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:26:50.003] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 39 19 38 52 55 62 58 62 55 59 56 55 51 44 21 29
[12:26:50.030] INFO: ----------------------------------------------------------------------
[12:26:50.030] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:26:50.030] INFO: ----------------------------------------------------------------------
[12:26:50.032] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:26:50.412] INFO: Expecting 41600 events.
[12:26:53.537] INFO: 41600 events read in total (2410ms).
[12:26:53.537] INFO: Test took 3505ms.
[12:26:53.537] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:53.537] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[12:26:53.537] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[12:26:53.538] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:26:53.960] INFO: PixTestAlive::maskTest() done
[12:26:53.960] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:26:53.979] INFO: ----------------------------------------------------------------------
[12:26:53.979] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:26:53.979] INFO: ----------------------------------------------------------------------
[12:26:53.981] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:26:54.360] INFO: Expecting 41600 events.
[12:26:58.740] INFO: 41600 events read in total (3665ms).
[12:26:58.742] INFO: Test took 4761ms.
[12:26:58.753] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:58.753] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[12:26:58.753] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[12:26:59.123] INFO: PixTestAlive::addressDecodingTest() done
[12:26:59.123] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:26:59.124] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[12:27:04.855] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:27:04.855] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[12:27:11.743] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[12:27:11.743] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[12:27:11.743] INFO: ----------------------------------------------------------------------
[12:27:11.743] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:27:11.743] INFO: ----------------------------------------------------------------------
[12:27:11.883] INFO: Expecting 768 events.
[12:27:13.016] INFO: 768 events read in total (418ms).
[12:27:13.016] INFO: Test took 1267ms.
[12:27:13.022] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:27:13.820] INFO: Expecting 41600 events.
[12:27:17.496] INFO: 41600 events read in total (3150ms).
[12:27:17.501] INFO: Test took 4479ms.
[12:27:17.620] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:17.620] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 677042
[12:27:17.620] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[12:27:17.621] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:27:17.672] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:27:18.337] INFO: Expecting 41600 events.
[12:27:22.039] INFO: 41600 events read in total (3175ms).
[12:27:22.044] INFO: Test took 4372ms.
[12:27:22.159] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:22.159] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684240
[12:27:22.159] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[12:27:22.159] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:27:22.213] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:27:22.878] INFO: Expecting 41600 events.
[12:27:26.646] INFO: 41600 events read in total (3241ms).
[12:27:26.650] INFO: Test took 4437ms.
[12:27:26.766] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:26.766] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684382
[12:27:26.766] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[12:27:26.766] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:27:26.820] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:27:27.487] INFO: Expecting 41600 events.
[12:27:31.264] INFO: 41600 events read in total (3251ms).
[12:27:31.269] INFO: Test took 4448ms.
[12:27:31.386] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:31.386] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685098
[12:27:31.386] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[12:27:31.386] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:27:31.440] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:27:32.105] INFO: Expecting 41600 events.
[12:27:35.828] INFO: 41600 events read in total (3196ms).
[12:27:35.832] INFO: Test took 4392ms.
[12:27:35.947] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:35.947] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683337
[12:27:35.947] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[12:27:35.947] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:27:36.002] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:27:36.668] INFO: Expecting 41600 events.
[12:27:40.448] INFO: 41600 events read in total (3253ms).
[12:27:40.452] INFO: Test took 4450ms.
[12:27:40.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:40.568] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683423
[12:27:40.568] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[12:27:40.569] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:27:40.624] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:27:41.290] INFO: Expecting 41600 events.
[12:27:45.078] INFO: 41600 events read in total (3261ms).
[12:27:45.083] INFO: Test took 4459ms.
[12:27:45.198] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:45.198] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685169
[12:27:45.198] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[12:27:45.199] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:27:45.252] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:27:45.918] INFO: Expecting 41600 events.
[12:27:49.709] INFO: 41600 events read in total (3265ms).
[12:27:49.713] INFO: Test took 4461ms.
[12:27:49.829] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:49.829] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685392
[12:27:49.829] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[12:27:49.830] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:27:49.884] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:27:50.550] INFO: Expecting 41600 events.
[12:27:54.354] INFO: 41600 events read in total (3277ms).
[12:27:54.359] INFO: Test took 4475ms.
[12:27:54.473] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:54.473] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685225
[12:27:54.473] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[12:27:54.474] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:27:54.528] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:27:55.195] INFO: Expecting 41600 events.
[12:27:58.974] INFO: 41600 events read in total (3254ms).
[12:27:58.979] INFO: Test took 4451ms.
[12:27:59.094] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:59.094] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685454
[12:27:59.094] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[12:27:59.095] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:27:59.148] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:27:59.814] INFO: Expecting 41600 events.
[12:28:03.614] INFO: 41600 events read in total (3273ms).
[12:28:03.619] INFO: Test took 4471ms.
[12:28:03.733] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:03.733] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685692
[12:28:03.733] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[12:28:03.734] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:28:03.788] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:28:04.453] INFO: Expecting 41600 events.
[12:28:08.258] INFO: 41600 events read in total (3279ms).
[12:28:08.263] INFO: Test took 4475ms.
[12:28:08.378] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:08.378] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684268
[12:28:08.378] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[12:28:08.378] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:28:08.432] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:28:09.098] INFO: Expecting 41600 events.
[12:28:12.889] INFO: 41600 events read in total (3265ms).
[12:28:12.894] INFO: Test took 4461ms.
[12:28:13.007] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:13.007] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684544
[12:28:13.007] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[12:28:13.008] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:28:13.061] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:28:13.727] INFO: Expecting 41600 events.
[12:28:17.520] INFO: 41600 events read in total (3266ms).
[12:28:17.525] INFO: Test took 4463ms.
[12:28:17.640] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:17.640] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683240
[12:28:17.640] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[12:28:17.641] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:28:17.694] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:28:18.360] INFO: Expecting 41600 events.
[12:28:22.166] INFO: 41600 events read in total (3280ms).
[12:28:22.171] INFO: Test took 4477ms.
[12:28:22.285] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:22.285] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 686495
[12:28:22.285] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[12:28:22.286] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:28:22.339] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:28:23.004] INFO: Expecting 41600 events.
[12:28:26.818] INFO: 41600 events read in total (3287ms).
[12:28:26.823] INFO: Test took 4484ms.
[12:28:26.939] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:26.939] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684659
[12:28:26.939] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[12:28:26.939] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:28:26.993] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:28:27.659] INFO: Expecting 41600 events.
[12:28:31.469] INFO: 41600 events read in total (3283ms).
[12:28:31.474] INFO: Test took 4481ms.
[12:28:31.589] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:31.589] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685476
[12:28:31.590] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[12:28:31.590] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:28:31.643] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:28:32.308] INFO: Expecting 41600 events.
[12:28:36.099] INFO: 41600 events read in total (3264ms).
[12:28:36.103] INFO: Test took 4460ms.
[12:28:36.221] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:36.221] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 682413
[12:28:36.221] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[12:28:36.221] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:28:36.275] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:28:36.940] INFO: Expecting 41600 events.
[12:28:40.706] INFO: 41600 events read in total (3240ms).
[12:28:40.711] INFO: Test took 4436ms.
[12:28:40.828] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:40.828] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683858
[12:28:40.828] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[12:28:40.828] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:28:40.882] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:28:41.547] INFO: Expecting 41600 events.
[12:28:45.137] INFO: 41600 events read in total (3064ms).
[12:28:45.142] INFO: Test took 4260ms.
[12:28:45.254] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:28:45.254] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 675907
[12:28:45.254] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[12:28:45.254] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:28:45.530] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 171 eff = 0.998966
[12:28:45.530] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 129 eff = 0.999543
[12:28:45.530] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 179 eff = 0.998846
[12:28:45.530] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 143 eff = 0.997885
[12:28:45.530] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 152 eff = 0.99738
[12:28:45.531] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 159 eff = 0.997837
[12:28:45.531] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 170 eff = 0.997788
[12:28:45.531] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 143 eff = 0.997668
[12:28:45.531] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 152 eff = 0.997957
[12:28:45.531] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 164 eff = 0.997284
[12:28:45.531] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 139 eff = 0.997716
[12:28:45.532] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 159 eff = 0.998197
[12:28:45.532] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 174 eff = 0.998197
[12:28:45.532] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 161 eff = 0.998702
[12:28:45.532] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 190 eff = 0.999591
[12:28:45.532] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 140 eff = 0.999519
[12:28:50.583] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[12:28:50.583] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[12:28:50.583] INFO: ----------------------------------------------------------------------
[12:28:50.583] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:28:50.584] INFO: ----------------------------------------------------------------------
[12:28:50.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[12:28:50.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[12:28:50.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[12:28:50.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[12:28:50.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[12:28:50.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[12:28:50.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[12:28:50.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[12:28:50.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[12:28:50.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[12:28:50.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[12:28:50.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[12:28:50.602] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:28:51.270] INFO: Expecting 208000 events.
[12:29:06.438] INFO: 208000 events read in total (14641ms).
[12:29:06.454] INFO: Test took 15852ms.
[12:29:07.059] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:07.059] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3129764
[12:29:07.059] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[12:29:07.059] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:29:07.505] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:29:07.505] INFO: number of red-efficiency pixels: 231 165 329 393 529 514 505 398 426 468 500 429 457 270 95 148
[12:29:07.505] INFO: number of X-ray hits detected: 138060 91697 154000 223182 244790 242472 239927 214365 226735 246304 243429 224111 229568 151605 85458 107501
[12:29:07.505] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:29:07.505] INFO: number of Vcal hits detected: 207756 207826 207651 207575 207416 207436 207448 207572 207545 207504 207454 207513 207515 207701 207901 207848
[12:29:07.505] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 99.9
[12:29:07.505] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.7 99.8 99.8 99.8 99.7 99.8 99.8 99.9 100.0 99.9
[12:29:07.505] INFO: X-ray hit rate [MHz/cm2]: 40.5 26.9 45.1 65.4 71.7 71.1 70.3 62.8 66.5 72.2 71.4 65.7 67.3 44.4 25.0 31.5
[12:29:07.505] INFO: PixTestHighRate::doXPixelAlive() done
[12:29:07.550] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[12:29:07.550] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[12:29:07.550] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[12:29:07.550] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[12:29:07.550] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[12:29:07.550] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[12:29:07.551] INFO: PixTest:: pg_setup set to default.
[12:29:10.815] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:29:10.815] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[12:29:12.663] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[12:29:12.663] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[12:29:12.664] INFO: ----------------------------------------------------------------------
[12:29:12.664] INFO: PixTestHighRate::runDaq() running for 2 seconds
[12:29:12.664] INFO: ----------------------------------------------------------------------
[12:29:12.869] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[12:29:12.869] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[12:29:12.869] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[12:29:12.869] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[12:29:12.869] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[12:29:12.869] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[12:29:12.869] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[12:29:12.869] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[12:29:12.869] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[12:29:12.869] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[12:29:12.869] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[12:29:12.869] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[12:29:13.827] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[12:29:15.830] INFO: data taking finished, elapsed time: 2 seconds.
[12:29:17.567] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39822 events with 582382 pixels
[12:29:17.572] INFO: PixTest:: pg_setup set to default.
[12:29:17.693] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[12:29:17.693] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[12:29:17.693] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[12:29:17.693] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[12:29:17.694] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[12:29:17.694] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[12:29:17.694] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[12:29:17.694] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[12:29:17.694] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[12:29:17.694] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[12:29:17.694] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[12:29:17.695] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[12:29:17.695] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[12:29:17.695] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[12:29:17.695] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[12:29:17.695] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[12:29:17.695] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[12:29:17.696] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[12:29:17.696] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[12:29:17.696] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[12:29:17.696] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[12:29:17.696] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[12:29:17.696] INFO: Pixels without X-ray hits (per ROC): 467 1032 615 131 112 124 125 131 120 138 143 251 113 609 1114 567
[12:29:17.696] INFO: PixTestHighRate::doRunDaq() done
[12:29:20.167] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:29:20.167] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C12_V0 -> hitMap_daqbbtest_mod
[12:29:21.623] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:29:21.624] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[12:29:21.625] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 469.5mA
[12:29:21.625] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[12:29:21.625] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:29:21.625] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:29:21.625] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:29:21.625] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:29:21.625] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:29:21.625] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:29:21.625] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:29:21.625] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:29:21.625] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:29:21.625] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:29:21.730] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:29:21.730] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:29:21.743] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:29:21.743] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:29:21.743] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:29:21.743] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:29:21.743] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:29:21.743] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:29:21.746] QUIET: Connection to board 58 closed.
[12:29:21.826] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[12:23:11.069] INFO: *** Welcome to pxar ***
[12:23:11.069] INFO: *** Today: 2016/08/26
[12:23:11.094] INFO: *** Version: v1.9.0-818-g96727
[12:23:11.094] INFO: readRocDacs: data/mq817_300/dacParameters35_C0.dat .. data/mq817_300/dacParameters35_C15.dat
[12:23:11.095] INFO: readTbmDacs: data/mq817_300/tbmParameters_C0a.dat .. data/mq817_300/tbmParameters_C0b.dat
[12:23:11.095] INFO: readMaskFile: data/mq817_300/defaultMaskFile.dat
[12:23:11.095] INFO: readTrimFile: data/mq817_300/trimParameters35_C0.dat .. data/mq817_300/trimParameters35_C15.dat
[12:23:11.245] INFO: clk: 4
[12:23:11.245] INFO: ctr: 4
[12:23:11.245] INFO: sda: 19
[12:23:11.245] INFO: tin: 9
[12:23:11.245] INFO: level: 15
[12:23:11.245] INFO: triggerdelay: 0
[12:23:11.245] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:23:11.245] INFO: Log level: DEBUG
[12:23:11.257] QUIET: Connection to board DTB_WRPRHI opened.
[12:23:11.260] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[12:23:11.263] INFO: RPC call hashes of host and DTB match: 398089610
[12:23:12.863] INFO: DUT info:
[12:23:12.863] INFO: The DUT currently contains the following objects:
[12:23:12.863] INFO: 2 TBM Cores tbm08c (2 ON)
[12:23:12.863] INFO: TBM Core alpha (0): 7 registers set
[12:23:12.864] INFO: TBM Core beta (1): 7 registers set
[12:23:12.864] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:23:12.864] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:23:12.864] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:23:12.864] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:23:12.864] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:23:12.864] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:23:12.864] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:23:12.864] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:23:12.865] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/ttyS0
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> ignoreproblems: checkbox(0)
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:23:12.866] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10000
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> ignorereadback: checkbox(0)
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:23:12.867] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:23:12.870] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35315712
[12:23:12.870] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1c32330
[12:23:12.870] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1bc0370
[12:23:12.870] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fcf31d94010
[12:23:12.870] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fcf37fff510
[12:23:12.870] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35323904 fPxarMemory = 0x7fcf31d94010
[12:23:12.871] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 384.3mA
[12:23:12.872] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[12:23:12.872] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[12:23:13.375] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x220d110
[12:23:13.438] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:23:13.438] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:23:13.438] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:23:13.445] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:23:13.445] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:23:13.445] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:23:13.459] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:23:13.459] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:23:13.480] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:23:13.480] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:23:13.494] INFO: PixTestCmd::init()
[12:23:13.515] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:23:13.515] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:23:13.515] INFO: readGainPedestalParameters data/mq817_300/phCalibrationFitErr35_C0.dat .. data/mq817_300/phCalibrationFitErr35_C15.dat
[12:23:13.764] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:23:13.764] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:23:13.778] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:23:13.784] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:23:13.784] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:23:13.784] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:23:13.808] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:23:13.808] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:23:13.808] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:23:13.808] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:23:13.808] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:23:13.814] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:23:13.814] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:23:13.830] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:23:13.830] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:23:13.830] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:23:13.830] INFO: readReadbackCal: data/mq817_300/readbackCal_C0.dat .. data/mq817_300/readbackCal_C15.dat
[12:23:13.839] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:23:13.848] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:23:13.848] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10000
[12:23:13.848] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:23:13.848] DEBUG: <PixTestTiming.cc/setParameter:L54> fIgnoreReadBack: 0
[12:23:13.848] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:23:13.866] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:23:13.866] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:23:26.970] INFO: ######################################################################
[12:23:26.970] INFO: PixTestAlive::doTest()
[12:23:26.970] INFO: ######################################################################
[12:23:26.974] INFO: ----------------------------------------------------------------------
[12:23:26.974] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:23:26.974] INFO: ----------------------------------------------------------------------
[12:23:26.976] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:23:27.356] INFO: Expecting 41600 events.
[12:23:31.736] INFO: 41600 events read in total (3661ms).
[12:23:31.881] INFO: Test took 4905ms.
[12:23:31.890] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:31.891] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[12:23:31.891] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[12:23:32.167] INFO: PixTestAlive::aliveTest() done
[12:23:32.167] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:23:32.167] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 65 34 60 74 91 81 75 81 66 68 77 71 71 63 26 37
[12:23:32.194] INFO: ----------------------------------------------------------------------
[12:23:32.194] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:23:32.194] INFO: ----------------------------------------------------------------------
[12:23:32.196] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:23:32.574] INFO: Expecting 41600 events.
[12:23:35.682] INFO: 41600 events read in total (2392ms).
[12:23:35.682] INFO: Test took 3486ms.
[12:23:35.682] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:35.682] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[12:23:35.682] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[12:23:35.683] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:23:36.105] INFO: PixTestAlive::maskTest() done
[12:23:36.105] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:23:36.127] INFO: ----------------------------------------------------------------------
[12:23:36.127] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:23:36.127] INFO: ----------------------------------------------------------------------
[12:23:36.129] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:23:36.506] INFO: Expecting 41600 events.
[12:23:40.923] INFO: 41600 events read in total (3701ms).
[12:23:40.925] INFO: Test took 4796ms.
[12:23:40.937] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:40.937] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[12:23:40.937] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[12:23:41.305] INFO: PixTestAlive::addressDecodingTest() done
[12:23:41.305] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:23:41.306] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[12:24:02.455] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:24:02.455] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C3_V0 -> PixelAlive_mod
[12:24:06.679] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[12:24:06.679] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[12:24:06.679] INFO: ----------------------------------------------------------------------
[12:24:06.679] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:24:06.679] INFO: ----------------------------------------------------------------------
[12:24:06.818] INFO: Expecting 768 events.
[12:24:07.953] INFO: 768 events read in total (419ms).
[12:24:07.953] INFO: Test took 1268ms.
[12:24:07.959] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:08.756] INFO: Expecting 41600 events.
[12:24:12.628] INFO: 41600 events read in total (3345ms).
[12:24:12.633] INFO: Test took 4675ms.
[12:24:12.787] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:12.787] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 891983
[12:24:12.787] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[12:24:12.788] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:12.852] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:13.518] INFO: Expecting 41600 events.
[12:24:17.486] INFO: 41600 events read in total (3441ms).
[12:24:17.491] INFO: Test took 4639ms.
[12:24:17.639] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:17.639] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 899197
[12:24:17.639] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[12:24:17.640] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:17.708] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:18.374] INFO: Expecting 41600 events.
[12:24:22.366] INFO: 41600 events read in total (3465ms).
[12:24:22.372] INFO: Test took 4664ms.
[12:24:22.518] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:22.518] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 901134
[12:24:22.518] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[12:24:22.518] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:22.588] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:23.254] INFO: Expecting 41600 events.
[12:24:27.252] INFO: 41600 events read in total (3471ms).
[12:24:27.257] INFO: Test took 4669ms.
[12:24:27.405] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:27.405] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 900656
[12:24:27.405] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[12:24:27.406] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:27.474] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:28.140] INFO: Expecting 41600 events.
[12:24:32.142] INFO: 41600 events read in total (3475ms).
[12:24:32.147] INFO: Test took 4673ms.
[12:24:32.294] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:32.294] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 902466
[12:24:32.294] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[12:24:32.294] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:32.363] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:33.029] INFO: Expecting 41600 events.
[12:24:37.036] INFO: 41600 events read in total (3480ms).
[12:24:37.041] INFO: Test took 4678ms.
[12:24:37.189] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:37.189] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 902782
[12:24:37.189] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[12:24:37.189] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:37.259] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:37.925] INFO: Expecting 41600 events.
[12:24:41.940] INFO: 41600 events read in total (3489ms).
[12:24:41.945] INFO: Test took 4686ms.
[12:24:42.093] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:42.093] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 901553
[12:24:42.093] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[12:24:42.094] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:42.163] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:42.829] INFO: Expecting 41600 events.
[12:24:46.847] INFO: 41600 events read in total (3491ms).
[12:24:46.853] INFO: Test took 4690ms.
[12:24:47.004] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:47.004] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 901769
[12:24:47.004] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[12:24:47.004] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:47.069] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:47.734] INFO: Expecting 41600 events.
[12:24:51.756] INFO: 41600 events read in total (3495ms).
[12:24:51.761] INFO: Test took 4692ms.
[12:24:51.910] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:51.910] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904834
[12:24:51.911] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[12:24:51.911] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:51.979] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:52.646] INFO: Expecting 41600 events.
[12:24:56.670] INFO: 41600 events read in total (3497ms).
[12:24:56.676] INFO: Test took 4697ms.
[12:24:56.823] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:56.823] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 901938
[12:24:56.823] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[12:24:56.823] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:24:56.892] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:57.558] INFO: Expecting 41600 events.
[12:25:01.576] INFO: 41600 events read in total (3491ms).
[12:25:01.581] INFO: Test took 4689ms.
[12:25:01.729] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:01.729] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 902575
[12:25:01.729] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[12:25:01.730] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:01.798] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:02.463] INFO: Expecting 41600 events.
[12:25:06.487] INFO: 41600 events read in total (3498ms).
[12:25:06.493] INFO: Test took 4695ms.
[12:25:06.642] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:06.642] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 902267
[12:25:06.642] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[12:25:06.642] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:06.711] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:07.377] INFO: Expecting 41600 events.
[12:25:11.406] INFO: 41600 events read in total (3502ms).
[12:25:11.411] INFO: Test took 4700ms.
[12:25:11.557] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:11.557] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904906
[12:25:11.557] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[12:25:11.557] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:11.626] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:12.291] INFO: Expecting 41600 events.
[12:25:16.330] INFO: 41600 events read in total (3512ms).
[12:25:16.335] INFO: Test took 4709ms.
[12:25:16.479] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:16.479] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 901270
[12:25:16.479] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[12:25:16.480] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:16.548] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:17.213] INFO: Expecting 41600 events.
[12:25:21.246] INFO: 41600 events read in total (3506ms).
[12:25:21.252] INFO: Test took 4704ms.
[12:25:21.399] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:21.399] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 902217
[12:25:21.399] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[12:25:21.399] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:21.468] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:22.133] INFO: Expecting 41600 events.
[12:25:26.166] INFO: 41600 events read in total (3506ms).
[12:25:26.171] INFO: Test took 4703ms.
[12:25:26.321] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:26.321] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 902190
[12:25:26.321] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[12:25:26.322] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:26.390] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:27.057] INFO: Expecting 41600 events.
[12:25:31.087] INFO: 41600 events read in total (3503ms).
[12:25:31.092] INFO: Test took 4702ms.
[12:25:31.238] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:31.238] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 901798
[12:25:31.238] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[12:25:31.238] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:31.308] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:31.975] INFO: Expecting 41600 events.
[12:25:35.998] INFO: 41600 events read in total (3496ms).
[12:25:36.003] INFO: Test took 4695ms.
[12:25:36.150] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:36.151] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 902428
[12:25:36.151] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[12:25:36.151] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:36.220] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:36.885] INFO: Expecting 41600 events.
[12:25:40.849] INFO: 41600 events read in total (3437ms).
[12:25:40.855] INFO: Test took 4635ms.
[12:25:41.000] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:41.001] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 899655
[12:25:41.001] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[12:25:41.001] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:41.070] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:41.735] INFO: Expecting 41600 events.
[12:25:45.546] INFO: 41600 events read in total (3284ms).
[12:25:45.551] INFO: Test took 4481ms.
[12:25:45.696] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:45.696] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 891232
[12:25:45.696] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[12:25:45.697] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:45.985] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 174 eff = 0.998221
[12:25:45.985] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 150 eff = 0.998822
[12:25:45.985] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 174 eff = 0.997452
[12:25:45.985] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 142 eff = 0.995625
[12:25:45.985] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 149 eff = 0.995048
[12:25:45.986] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 156 eff = 0.994712
[12:25:45.986] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 169 eff = 0.995529
[12:25:45.986] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 143 eff = 0.996034
[12:25:45.986] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 155 eff = 0.995841
[12:25:45.986] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 167 eff = 0.994976
[12:25:45.986] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 141 eff = 0.995577
[12:25:45.986] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 157 eff = 0.995553
[12:25:45.987] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 175 eff = 0.995337
[12:25:45.987] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 161 eff = 0.997524
[12:25:45.987] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 180 eff = 0.999327
[12:25:45.987] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 156 eff = 0.99887
[12:25:48.583] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[12:25:48.583] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[12:25:48.583] INFO: ----------------------------------------------------------------------
[12:25:48.583] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:25:48.583] INFO: ----------------------------------------------------------------------
[12:25:48.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[12:25:48.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[12:25:48.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[12:25:48.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[12:25:48.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[12:25:48.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[12:25:48.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[12:25:48.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[12:25:48.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[12:25:48.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[12:25:48.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[12:25:48.584] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[12:25:48.601] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:49.270] INFO: Expecting 208000 events.
[12:26:06.240] INFO: 208000 events read in total (16443ms).
[12:26:06.261] INFO: Test took 17660ms.
[12:26:07.083] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:07.083] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4196699
[12:26:07.083] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[12:26:07.084] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:26:07.589] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:26:07.589] INFO: number of red-efficiency pixels: 324 193 575 817 880 965 891 768 767 875 912 774 793 455 167 196
[12:26:07.589] INFO: number of X-ray hits detected: 185406 124184 208128 300648 328718 325929 323531 289828 305505 331975 328260 303145 308209 205515 115556 145602
[12:26:07.589] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:26:07.589] INFO: number of Vcal hits detected: 207661 207799 207271 207065 206966 206806 206977 207107 207104 206964 206928 207121 207105 207461 207822 207796
[12:26:07.590] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.6 99.5 99.5 99.5 99.6 99.6 99.5 99.5 99.6 99.6 99.8 99.9 99.9
[12:26:07.590] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.6 99.5 99.4 99.5 99.6 99.6 99.5 99.5 99.6 99.6 99.7 99.9 99.9
[12:26:07.590] INFO: X-ray hit rate [MHz/cm2]: 54.3 36.4 61.0 88.1 96.3 95.5 94.8 85.0 89.5 97.3 96.2 88.9 90.3 60.2 33.9 42.7
[12:26:07.590] INFO: PixTestHighRate::doXPixelAlive() done
[12:26:07.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[12:26:07.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[12:26:07.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[12:26:07.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[12:26:07.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[12:26:07.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[12:26:07.635] INFO: PixTest:: pg_setup set to default.
[12:26:16.079] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:26:16.079] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[12:26:19.367] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[12:26:19.367] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[12:26:19.368] INFO: ----------------------------------------------------------------------
[12:26:19.368] INFO: PixTestHighRate::runDaq() running for 2 seconds
[12:26:19.368] INFO: ----------------------------------------------------------------------
[12:26:19.567] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[12:26:19.567] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[12:26:19.567] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[12:26:19.567] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[12:26:19.567] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[12:26:19.567] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[12:26:19.567] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[12:26:19.567] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[12:26:19.567] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[12:26:19.567] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[12:26:19.567] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[12:26:19.567] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[12:26:20.524] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[12:26:22.527] INFO: data taking finished, elapsed time: 2 seconds.
[12:26:24.484] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39825 events with 790283 pixels
[12:26:24.489] INFO: PixTest:: pg_setup set to default.
[12:26:24.592] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[12:26:24.592] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[12:26:24.592] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[12:26:24.592] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[12:26:24.593] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[12:26:24.593] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[12:26:24.593] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[12:26:24.593] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[12:26:24.593] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[12:26:24.593] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[12:26:24.593] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[12:26:24.594] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[12:26:24.594] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[12:26:24.594] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[12:26:24.594] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[12:26:24.594] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[12:26:24.594] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[12:26:24.594] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[12:26:24.594] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[12:26:24.594] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[12:26:24.595] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[12:26:24.595] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[12:26:24.595] INFO: Pixels without X-ray hits (per ROC): 331 851 491 88 78 84 92 91 86 96 95 169 85 495 904 387
[12:26:24.595] INFO: PixTestHighRate::doRunDaq() done
[12:26:29.343] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:26:29.343] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C13_V0 -> hitMap_daqbbtest_mod
[12:26:31.135] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:26:31.136] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[12:26:31.137] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 469.5mA
[12:26:31.137] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[12:26:31.137] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:26:31.137] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:26:31.137] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:26:31.137] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:26:31.137] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:26:31.137] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:26:31.137] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:26:31.137] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:26:31.137] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:26:31.138] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:26:31.246] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:26:31.246] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:26:31.259] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:26:31.259] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:26:31.259] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:26:31.259] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:26:31.259] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:26:31.259] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:26:31.262] QUIET: Connection to board 58 closed.
[12:26:31.341] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries