Test Date: 1973-11-29 16:33
Analysis date: 2016-06-10 15:51
Logfile
hrData_40.log
[10:58:47.580] INFO: *** Welcome to pxar ***
[10:58:47.580] INFO: *** Today: 2016/06/09
[10:58:47.604] INFO: *** Version: v1.9.0-814-g7497
[10:58:47.604] INFO: readRocDacs: data/mp320/dacParameters35_C0.dat .. data/mp320/dacParameters35_C15.dat
[10:58:47.605] INFO: readTbmDacs: data/mp320/tbmParameters_C0a.dat .. data/mp320/tbmParameters_C0b.dat
[10:58:47.605] INFO: readMaskFile: data/mp320/defaultMaskFile.dat
[10:58:47.605] INFO: readTrimFile: data/mp320/trimParameters35_C0.dat .. data/mp320/trimParameters35_C15.dat
[10:58:47.757] INFO: clk: 4
[10:58:47.757] INFO: ctr: 4
[10:58:47.757] INFO: sda: 19
[10:58:47.757] INFO: tin: 9
[10:58:47.757] INFO: level: 15
[10:58:47.757] INFO: triggerdelay: 0
[10:58:47.757] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:58:47.757] INFO: Log level: DEBUG
[10:58:47.768] QUIET: Connection to board DTB_WRMNMR opened.
[10:58:47.771] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[10:58:47.774] INFO: RPC call hashes of host and DTB match: 398089610
[10:58:49.389] INFO: DUT info:
[10:58:49.389] INFO: The DUT currently contains the following objects:
[10:58:49.389] INFO: 2 TBM Cores tbm08c (2 ON)
[10:58:49.389] INFO: TBM Core alpha (0): 7 registers set
[10:58:49.389] INFO: TBM Core beta (1): 7 registers set
[10:58:49.389] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:58:49.389] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.389] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.389] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.389] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.389] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.389] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.389] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.389] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.389] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.389] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.389] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.389] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.389] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.389] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.390] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.390] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[10:58:49.390] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:58:49.391] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[10:58:49.392] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:58:49.393] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:58:49.395] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35508224
[10:58:49.395] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1a50bd0
[10:58:49.395] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x17f2e60
[10:58:49.395] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f834dd94010
[10:58:49.395] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f8353fff510
[10:58:49.395] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35516416 fPxarMemory = 0x7f834dd94010
[10:58:49.397] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[10:58:49.398] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 463.9mA
[10:58:49.398] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[10:58:49.898] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1e3b900
[10:58:49.962] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[10:58:49.962] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[10:58:49.962] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[10:58:49.968] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[10:58:49.968] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[10:58:49.968] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[10:58:49.979] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[10:58:49.979] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[10:58:49.995] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[10:58:49.995] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[10:58:50.019] INFO: PixTestCmd::init()
[10:58:50.039] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[10:58:50.039] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[10:58:50.039] INFO: readGainPedestalParameters data/mp320/phCalibrationFitErr35_C0.dat .. data/mp320/phCalibrationFitErr35_C15.dat
[10:58:50.284] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[10:58:50.284] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[10:58:50.299] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[10:58:50.305] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[10:58:50.305] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[10:58:50.305] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[10:58:50.325] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[10:58:50.325] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[10:58:50.325] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[10:58:50.325] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[10:58:50.325] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[10:58:50.331] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[10:58:50.331] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[10:58:50.345] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[10:58:50.345] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[10:58:50.345] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[10:58:50.345] INFO: readReadbackCal: data/mp320/readbackCal_C0.dat .. data/mp320/readbackCal_C15.dat
[10:58:50.358] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[10:58:50.366] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[10:58:50.366] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[10:58:50.366] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[10:58:50.366] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[10:58:50.383] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[10:58:50.383] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[10:58:57.854] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[10:59:00.830] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[10:59:02.811] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[10:59:02.811] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[10:59:02.811] INFO: ----------------------------------------------------------------------
[10:59:02.811] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[10:59:02.811] INFO: ----------------------------------------------------------------------
[10:59:03.785] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[10:59:15.700] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:59:15.704] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:59:42.926] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1174018 events.
[10:59:49.692] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1174018, pixels seen in all events: 6163484
[10:59:49.881] INFO: Resuming triggers.
[11:00:01.802] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:00:01.843] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:00:28.911] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1174389 events.
[11:00:35.640] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1174389, pixels seen in all events: 6158724
[11:00:35.732] INFO: Resuming triggers.
[11:00:47.654] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[11:00:47.693] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:01:14.760] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1174449 events.
[11:01:21.497] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1174449, pixels seen in all events: 6157565
[11:01:21.667] INFO: Resuming triggers.
[11:01:33.588] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[11:01:33.628] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:02:00.571] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1174410 events.
[11:02:07.322] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1174410, pixels seen in all events: 6158185
[11:02:07.506] INFO: Resuming triggers.
[11:02:19.430] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[11:02:19.469] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:02:46.575] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1174697 events.
[11:02:53.213] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1174697, pixels seen in all events: 6154777
[11:02:53.470] INFO: Resuming triggers.
[11:02:53.950] INFO: data taking finished, elapsed time: 60 seconds.
[11:02:54.146] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:02:55.406] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 47236 events.
[11:02:55.680] DEBUG: <PixTestXray.cc/processData:L823> # events read: 47236, pixels seen in all events: 247999
[11:02:55.725] INFO: PixTest:: pg_setup set to default.
[11:02:55.773] INFO: PixTestXray::doPhRun() done
[11:03:06.875] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:03:06.875] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hMap_02ma_C14_V0 -> hMap_02ma_mod
[11:03:08.840] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:03:08.840] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 381.9mA
[11:03:08.841] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 464.7mA
[11:03:08.841] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[11:03:08.841] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:03:08.841] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:03:08.841] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:03:08.841] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:03:08.841] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:03:08.842] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:03:08.842] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:03:08.842] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:03:08.842] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:03:08.842] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:03:08.842] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:03:08.842] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:03:08.842] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:03:08.842] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:03:08.842] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:03:08.842] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[11:03:08.842] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:03:08.842] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:03:09.126] QUIET: Connection to board 43 closed.
[11:03:09.206] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[11:03:15.098] INFO: *** Welcome to pxar ***
[11:03:15.098] INFO: *** Today: 2016/06/09
[11:03:15.121] INFO: *** Version: v1.9.0-814-g7497
[11:03:15.121] INFO: readRocDacs: data/mp320/dacParameters35_C0.dat .. data/mp320/dacParameters35_C15.dat
[11:03:15.122] INFO: readTbmDacs: data/mp320/tbmParameters_C0a.dat .. data/mp320/tbmParameters_C0b.dat
[11:03:15.122] INFO: readMaskFile: data/mp320/defaultMaskFile.dat
[11:03:15.122] INFO: readTrimFile: data/mp320/trimParameters35_C0.dat .. data/mp320/trimParameters35_C15.dat
[11:03:15.272] INFO: clk: 4
[11:03:15.272] INFO: ctr: 4
[11:03:15.272] INFO: sda: 19
[11:03:15.272] INFO: tin: 9
[11:03:15.272] INFO: level: 15
[11:03:15.272] INFO: triggerdelay: 0
[11:03:15.272] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[11:03:15.272] INFO: Log level: DEBUG
[11:03:15.282] QUIET: Connection to board DTB_WRMNMR opened.
[11:03:15.286] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[11:03:15.288] INFO: RPC call hashes of host and DTB match: 398089610
[11:03:16.891] INFO: DUT info:
[11:03:16.891] INFO: The DUT currently contains the following objects:
[11:03:16.891] INFO: 2 TBM Cores tbm08c (2 ON)
[11:03:16.891] INFO: TBM Core alpha (0): 7 registers set
[11:03:16.891] INFO: TBM Core beta (1): 7 registers set
[11:03:16.891] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:03:16.891] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.891] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.891] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.891] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.891] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.891] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.891] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.891] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.891] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.891] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.891] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.891] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.891] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.892] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.892] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.892] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:03:16.892] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:03:16.893] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:03:16.894] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:03:16.895] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:03:16.897] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33648640
[11:03:16.897] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x182abd0
[11:03:16.897] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x15cce60
[11:03:16.897] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f779dd94010
[11:03:16.897] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f77a3fff510
[11:03:16.897] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33656832 fPxarMemory = 0x7f779dd94010
[11:03:16.899] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[11:03:16.900] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 463.9mA
[11:03:16.900] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[11:03:17.399] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1c157b0
[11:03:17.463] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:03:17.463] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:03:17.464] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:03:17.469] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:03:17.469] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:03:17.469] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:03:17.480] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:03:17.480] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:03:17.500] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:03:17.500] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:03:17.512] INFO: PixTestCmd::init()
[11:03:17.518] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:03:17.518] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:03:17.518] INFO: readGainPedestalParameters data/mp320/phCalibrationFitErr35_C0.dat .. data/mp320/phCalibrationFitErr35_C15.dat
[11:03:17.762] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:03:17.762] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:03:17.776] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:03:17.783] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:03:17.783] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:03:17.783] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:03:17.805] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:03:17.805] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:03:17.805] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:03:17.805] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:03:17.805] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:03:17.811] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:03:17.811] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:03:17.827] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:03:17.827] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:03:17.827] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:03:17.827] INFO: readReadbackCal: data/mp320/readbackCal_C0.dat .. data/mp320/readbackCal_C15.dat
[11:03:17.839] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:03:17.848] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[11:03:17.848] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[11:03:17.849] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[11:03:17.849] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[11:03:17.867] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:03:17.867] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:03:24.318] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[11:03:26.238] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[11:03:27.755] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[11:03:27.755] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[11:03:27.755] INFO: ----------------------------------------------------------------------
[11:03:27.755] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[11:03:27.755] INFO: ----------------------------------------------------------------------
[11:03:28.728] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[11:03:36.594] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[11:03:36.597] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:04:02.558] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 774986 events.
[11:04:14.369] DEBUG: <PixTestXray.cc/processData:L823> # events read: 774986, pixels seen in all events: 10953055
[11:04:14.623] INFO: Resuming triggers.
[11:04:22.496] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[11:04:22.529] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:04:48.500] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 775562 events.
[11:05:00.253] DEBUG: <PixTestXray.cc/processData:L823> # events read: 775562, pixels seen in all events: 10944924
[11:05:00.418] INFO: Resuming triggers.
[11:05:08.292] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:05:08.332] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:05:34.374] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 775650 events.
[11:05:46.402] DEBUG: <PixTestXray.cc/processData:L823> # events read: 775650, pixels seen in all events: 10943595
[11:05:46.580] INFO: Resuming triggers.
[11:05:54.453] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[11:05:54.487] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:06:20.519] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 775600 events.
[11:06:32.414] DEBUG: <PixTestXray.cc/processData:L823> # events read: 775600, pixels seen in all events: 10945256
[11:06:32.580] INFO: Resuming triggers.
[11:06:40.457] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[11:06:40.492] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:07:06.403] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 775931 events.
[11:07:18.305] DEBUG: <PixTestXray.cc/processData:L823> # events read: 775931, pixels seen in all events: 10940185
[11:07:18.440] INFO: Resuming triggers.
[11:07:26.317] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[11:07:26.352] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:07:52.244] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 775946 events.
[11:08:04.154] DEBUG: <PixTestXray.cc/processData:L823> # events read: 775946, pixels seen in all events: 10939892
[11:08:04.334] INFO: Resuming triggers.
[11:08:12.208] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[11:08:12.242] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:08:38.251] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 775704 events.
[11:08:50.262] DEBUG: <PixTestXray.cc/processData:L823> # events read: 775704, pixels seen in all events: 10945170
[11:08:50.428] INFO: Resuming triggers.
[11:08:55.389] INFO: data taking finished, elapsed time: 60 seconds.
[11:08:55.585] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:09:11.973] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 488655 events.
[11:09:19.388] DEBUG: <PixTestXray.cc/processData:L823> # events read: 488655, pixels seen in all events: 6890575
[11:09:19.508] INFO: PixTest:: pg_setup set to default.
[11:09:19.550] INFO: PixTestXray::doPhRun() done
[11:09:48.867] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:09:48.867] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hMap_06ma_C13_V0 -> hMap_06ma_mod
[11:09:51.931] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:09:51.932] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 382.7mA
[11:09:51.932] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 465.5mA
[11:09:51.932] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[11:09:51.932] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:09:51.933] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:09:51.933] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:09:51.933] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:09:51.933] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:09:51.933] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:09:51.933] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:09:51.933] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:09:51.933] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:09:51.933] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:09:51.933] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:09:51.933] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:09:51.933] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:09:51.933] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:09:51.934] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:09:51.934] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[11:09:51.934] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:09:51.934] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:09:52.253] QUIET: Connection to board 43 closed.
[11:09:52.333] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[10:53:39.252] INFO: *** Welcome to pxar ***
[10:53:39.252] INFO: *** Today: 2016/06/09
[10:53:39.276] INFO: *** Version: v1.9.0-814-g7497
[10:53:39.276] INFO: readRocDacs: data/mp320/dacParameters35_C0.dat .. data/mp320/dacParameters35_C15.dat
[10:53:39.277] INFO: readTbmDacs: data/mp320/tbmParameters_C0a.dat .. data/mp320/tbmParameters_C0b.dat
[10:53:39.277] INFO: readMaskFile: data/mp320/defaultMaskFile.dat
[10:53:39.277] INFO: readTrimFile: data/mp320/trimParameters35_C0.dat .. data/mp320/trimParameters35_C15.dat
[10:53:39.426] INFO: clk: 4
[10:53:39.426] INFO: ctr: 4
[10:53:39.426] INFO: sda: 19
[10:53:39.426] INFO: tin: 9
[10:53:39.426] INFO: level: 15
[10:53:39.426] INFO: triggerdelay: 0
[10:53:39.426] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:53:39.426] INFO: Log level: DEBUG
[10:53:39.437] QUIET: Connection to board DTB_WRMNMR opened.
[10:53:39.441] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[10:53:39.444] INFO: RPC call hashes of host and DTB match: 398089610
[10:53:41.048] INFO: DUT info:
[10:53:41.048] INFO: The DUT currently contains the following objects:
[10:53:41.048] INFO: 2 TBM Cores tbm08c (2 ON)
[10:53:41.048] INFO: TBM Core alpha (0): 7 registers set
[10:53:41.048] INFO: TBM Core beta (1): 7 registers set
[10:53:41.048] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:53:41.048] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.048] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.048] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.048] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.048] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.048] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.048] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.048] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.048] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.048] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.048] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.049] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.049] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.049] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.049] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.049] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[10:53:41.049] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:53:41.050] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[10:53:41.051] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:53:41.052] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:53:41.054] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31793152
[10:53:41.054] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xe66bd0
[10:53:41.054] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xc08e60
[10:53:41.054] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f0925d94010
[10:53:41.054] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f092bfff510
[10:53:41.054] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31801344 fPxarMemory = 0x7f0925d94010
[10:53:41.056] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[10:53:41.057] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 463.9mA
[10:53:41.057] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[10:53:41.554] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x12517a0
[10:53:41.618] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[10:53:41.618] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[10:53:41.618] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[10:53:41.625] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[10:53:41.625] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[10:53:41.625] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[10:53:41.639] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[10:53:41.641] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[10:53:41.666] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[10:53:41.666] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[10:53:41.674] INFO: PixTestCmd::init()
[10:53:41.690] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[10:53:41.690] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[10:53:41.690] INFO: readGainPedestalParameters data/mp320/phCalibrationFitErr35_C0.dat .. data/mp320/phCalibrationFitErr35_C15.dat
[10:53:41.933] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[10:53:41.933] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[10:53:41.948] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[10:53:41.954] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[10:53:41.954] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[10:53:41.954] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[10:53:41.975] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[10:53:41.975] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[10:53:41.975] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[10:53:41.975] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[10:53:41.975] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[10:53:41.981] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[10:53:41.982] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[10:53:41.998] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[10:53:41.998] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[10:53:41.998] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[10:53:41.998] INFO: readReadbackCal: data/mp320/readbackCal_C0.dat .. data/mp320/readbackCal_C15.dat
[10:53:42.009] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[10:53:42.017] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[10:53:42.017] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[10:53:42.017] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[10:53:42.017] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[10:53:42.035] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[10:53:42.035] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[10:53:45.486] INFO: ######################################################################
[10:53:45.486] INFO: PixTestAlive::doTest()
[10:53:45.486] INFO: ######################################################################
[10:53:45.489] INFO: ----------------------------------------------------------------------
[10:53:45.489] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:53:45.489] INFO: ----------------------------------------------------------------------
[10:53:45.492] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:53:45.871] INFO: Expecting 41600 events.
[10:53:50.106] INFO: 41600 events read in total (3517ms).
[10:53:50.254] INFO: Test took 4762ms.
[10:53:50.264] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:50.264] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[10:53:50.264] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[10:53:50.534] INFO: PixTestAlive::aliveTest() done
[10:53:50.534] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:53:50.534] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 28 20 28 31 40 39 44 40 32 37 46 39 47 32 9 25
[10:53:50.558] INFO: ----------------------------------------------------------------------
[10:53:50.558] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:53:50.558] INFO: ----------------------------------------------------------------------
[10:53:50.560] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:53:50.938] INFO: Expecting 41600 events.
[10:53:53.866] INFO: 41600 events read in total (2213ms).
[10:53:53.866] INFO: Test took 3306ms.
[10:53:53.866] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:53.866] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[10:53:53.866] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[10:53:53.867] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:53:54.289] INFO: PixTestAlive::maskTest() done
[10:53:54.289] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:53:54.312] INFO: ----------------------------------------------------------------------
[10:53:54.312] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:53:54.312] INFO: ----------------------------------------------------------------------
[10:53:54.314] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:53:54.691] INFO: Expecting 41600 events.
[10:53:58.901] INFO: 41600 events read in total (3495ms).
[10:53:58.902] INFO: Test took 4588ms.
[10:53:58.912] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:58.912] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[10:53:58.912] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[10:53:59.282] INFO: PixTestAlive::addressDecodingTest() done
[10:53:59.282] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:53:59.282] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[10:54:01.387] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[10:54:01.387] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[10:54:01.387] INFO: ----------------------------------------------------------------------
[10:54:01.387] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:54:01.387] INFO: ----------------------------------------------------------------------
[10:54:01.527] INFO: Expecting 768 events.
[10:54:02.656] INFO: 768 events read in total (414ms).
[10:54:02.656] INFO: Test took 1264ms.
[10:54:02.662] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:03.459] INFO: Expecting 41600 events.
[10:54:06.691] INFO: 41600 events read in total (2705ms).
[10:54:06.696] INFO: Test took 4034ms.
[10:54:06.781] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:06.781] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 485940
[10:54:06.781] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[10:54:06.782] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:06.821] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:07.486] INFO: Expecting 41600 events.
[10:54:10.817] INFO: 41600 events read in total (2805ms).
[10:54:10.820] INFO: Test took 3999ms.
[10:54:10.902] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:10.902] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 493543
[10:54:10.902] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[10:54:10.902] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:10.942] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:11.607] INFO: Expecting 41600 events.
[10:54:14.989] INFO: 41600 events read in total (2855ms).
[10:54:14.994] INFO: Test took 4052ms.
[10:54:15.075] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:15.075] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496501
[10:54:15.075] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[10:54:15.075] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:15.115] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:15.780] INFO: Expecting 41600 events.
[10:54:19.150] INFO: 41600 events read in total (2843ms).
[10:54:19.153] INFO: Test took 4038ms.
[10:54:19.235] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:19.235] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495307
[10:54:19.235] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[10:54:19.235] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:19.276] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:19.941] INFO: Expecting 41600 events.
[10:54:23.320] INFO: 41600 events read in total (2853ms).
[10:54:23.324] INFO: Test took 4048ms.
[10:54:23.404] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:23.405] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495368
[10:54:23.405] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[10:54:23.405] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:23.446] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:24.110] INFO: Expecting 41600 events.
[10:54:27.496] INFO: 41600 events read in total (2859ms).
[10:54:27.499] INFO: Test took 4053ms.
[10:54:27.580] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:27.580] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495921
[10:54:27.580] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[10:54:27.580] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:27.621] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:28.286] INFO: Expecting 41600 events.
[10:54:31.667] INFO: 41600 events read in total (2855ms).
[10:54:31.671] INFO: Test took 4050ms.
[10:54:31.753] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:31.753] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 494938
[10:54:31.753] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[10:54:31.754] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:31.795] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:32.460] INFO: Expecting 41600 events.
[10:54:35.858] INFO: 41600 events read in total (2871ms).
[10:54:35.861] INFO: Test took 4066ms.
[10:54:35.943] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:35.943] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496580
[10:54:35.943] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[10:54:35.943] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:35.985] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:36.650] INFO: Expecting 41600 events.
[10:54:40.050] INFO: 41600 events read in total (2873ms).
[10:54:40.054] INFO: Test took 4069ms.
[10:54:40.136] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:40.136] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496005
[10:54:40.136] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[10:54:40.136] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:40.177] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:40.842] INFO: Expecting 41600 events.
[10:54:44.231] INFO: 41600 events read in total (2863ms).
[10:54:44.235] INFO: Test took 4058ms.
[10:54:44.316] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:44.316] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495263
[10:54:44.316] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[10:54:44.316] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:44.357] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:45.022] INFO: Expecting 41600 events.
[10:54:48.409] INFO: 41600 events read in total (2860ms).
[10:54:48.413] INFO: Test took 4056ms.
[10:54:48.495] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:48.495] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496365
[10:54:48.495] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[10:54:48.495] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:48.536] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:49.200] INFO: Expecting 41600 events.
[10:54:52.591] INFO: 41600 events read in total (2864ms).
[10:54:52.595] INFO: Test took 4059ms.
[10:54:52.676] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:52.676] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496315
[10:54:52.676] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[10:54:52.676] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:52.718] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:53.383] INFO: Expecting 41600 events.
[10:54:56.767] INFO: 41600 events read in total (2857ms).
[10:54:56.771] INFO: Test took 4053ms.
[10:54:56.853] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:56.854] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496421
[10:54:56.854] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[10:54:56.854] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:56.895] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:57.560] INFO: Expecting 41600 events.
[10:55:00.953] INFO: 41600 events read in total (2866ms).
[10:55:00.957] INFO: Test took 4062ms.
[10:55:01.040] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:01.040] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496333
[10:55:01.040] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[10:55:01.040] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:55:01.081] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:55:01.747] INFO: Expecting 41600 events.
[10:55:05.138] INFO: 41600 events read in total (2864ms).
[10:55:05.142] INFO: Test took 4061ms.
[10:55:05.224] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:05.224] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496343
[10:55:05.224] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[10:55:05.224] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:55:05.265] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:55:05.930] INFO: Expecting 41600 events.
[10:55:09.320] INFO: 41600 events read in total (2863ms).
[10:55:09.324] INFO: Test took 4059ms.
[10:55:09.404] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:09.404] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496139
[10:55:09.404] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[10:55:09.404] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:55:09.446] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:55:10.110] INFO: Expecting 41600 events.
[10:55:13.496] INFO: 41600 events read in total (2859ms).
[10:55:13.500] INFO: Test took 4054ms.
[10:55:13.582] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:13.582] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495469
[10:55:13.582] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[10:55:13.582] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:55:13.623] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:55:14.289] INFO: Expecting 41600 events.
[10:55:17.680] INFO: 41600 events read in total (2865ms).
[10:55:17.684] INFO: Test took 4061ms.
[10:55:17.767] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:17.767] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495767
[10:55:17.767] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[10:55:17.768] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:55:17.809] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:55:18.475] INFO: Expecting 41600 events.
[10:55:21.824] INFO: 41600 events read in total (2822ms).
[10:55:21.828] INFO: Test took 4019ms.
[10:55:21.909] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:21.909] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 494578
[10:55:21.909] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[10:55:21.910] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:55:21.951] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:55:22.616] INFO: Expecting 41600 events.
[10:55:25.786] INFO: 41600 events read in total (2643ms).
[10:55:25.790] INFO: Test took 3839ms.
[10:55:25.870] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:25.870] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 484864
[10:55:25.870] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[10:55:25.871] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:55:26.134] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 150 eff = 0.999567
[10:55:26.134] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 140 eff = 0.999832
[10:55:26.134] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 158 eff = 0.999279
[10:55:26.134] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 169 eff = 0.998942
[10:55:26.134] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 132 eff = 0.998966
[10:55:26.134] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 164 eff = 0.999038
[10:55:26.135] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 148 eff = 0.99899
[10:55:26.135] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 150 eff = 0.999135
[10:55:26.135] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 179 eff = 0.998942
[10:55:26.135] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 152 eff = 0.998654
[10:55:26.135] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 150 eff = 0.998918
[10:55:26.135] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 156 eff = 0.999111
[10:55:26.135] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 172 eff = 0.998654
[10:55:26.136] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 134 eff = 0.999447
[10:55:26.136] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 154 eff = 0.999712
[10:55:26.136] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 155 eff = 0.999663
[10:55:38.635] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[10:55:38.635] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[10:55:38.635] INFO: ----------------------------------------------------------------------
[10:55:38.635] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:55:38.635] INFO: ----------------------------------------------------------------------
[10:55:38.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[10:55:38.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[10:55:38.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[10:55:38.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[10:55:38.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[10:55:38.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[10:55:38.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[10:55:38.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[10:55:38.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[10:55:38.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[10:55:38.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[10:55:38.635] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[10:55:38.653] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:55:39.322] INFO: Expecting 208000 events.
[10:55:52.767] INFO: 208000 events read in total (12918ms).
[10:55:52.779] INFO: Test took 14126ms.
[10:55:53.170] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:53.170] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2196719
[10:55:53.170] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[10:55:53.171] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:55:53.548] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:55:53.549] INFO: number of red-efficiency pixels: 129 90 181 237 271 274 242 253 291 290 258 234 250 152 82 97
[10:55:53.549] INFO: number of X-ray hits detected: 92374 62124 102215 159336 165906 169106 167640 137336 146242 175103 171991 160335 167825 104276 65507 82844
[10:55:53.549] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:55:53.549] INFO: number of Vcal hits detected: 207868 207905 207814 207754 207718 207706 207744 207738 207646 207700 207721 207759 207739 207843 207916 207901
[10:55:53.549] 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
[10:55:53.549] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[10:55:53.549] INFO: X-ray hit rate [MHz/cm2]: 27.1 18.2 30.0 46.7 48.6 49.6 49.1 40.3 42.9 51.3 50.4 47.0 49.2 30.6 19.2 24.3
[10:55:53.549] INFO: PixTestHighRate::doXPixelAlive() done
[10:55:53.594] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[10:55:53.594] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[10:55:53.594] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[10:55:53.594] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[10:55:53.594] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[10:55:53.594] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[10:55:53.594] INFO: PixTest:: pg_setup set to default.
[10:56:00.843] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[10:56:00.843] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C9_V0 -> highRate_xraymap_mod
[10:56:02.339] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:56:02.339] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 381.9mA
[10:56:02.340] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 463.9mA
[10:56:02.340] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[10:56:02.340] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:56:02.340] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:56:02.340] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:56:02.340] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:56:02.340] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:56:02.341] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:56:02.341] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:56:02.341] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:56:02.341] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:56:02.341] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:56:02.428] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:56:02.428] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:56:02.438] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:56:02.438] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:56:02.438] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:56:02.438] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:56:02.438] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:56:02.438] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:56:02.440] QUIET: Connection to board 43 closed.
[10:56:02.520] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[10:51:09.651] INFO: *** Welcome to pxar ***
[10:51:09.651] INFO: *** Today: 2016/06/09
[10:51:09.675] INFO: *** Version: v1.9.0-814-g7497
[10:51:09.675] INFO: readRocDacs: data/mp320/dacParameters35_C0.dat .. data/mp320/dacParameters35_C15.dat
[10:51:09.676] INFO: readTbmDacs: data/mp320/tbmParameters_C0a.dat .. data/mp320/tbmParameters_C0b.dat
[10:51:09.676] INFO: readMaskFile: data/mp320/defaultMaskFile.dat
[10:51:09.676] INFO: readTrimFile: data/mp320/trimParameters35_C0.dat .. data/mp320/trimParameters35_C15.dat
[10:51:09.827] INFO: clk: 4
[10:51:09.827] INFO: ctr: 4
[10:51:09.827] INFO: sda: 19
[10:51:09.827] INFO: tin: 9
[10:51:09.827] INFO: level: 15
[10:51:09.827] INFO: triggerdelay: 0
[10:51:09.827] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:51:09.827] INFO: Log level: DEBUG
[10:51:09.838] QUIET: Connection to board DTB_WRMNMR opened.
[10:51:09.841] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[10:51:09.844] INFO: RPC call hashes of host and DTB match: 398089610
[10:51:11.448] INFO: DUT info:
[10:51:11.448] INFO: The DUT currently contains the following objects:
[10:51:11.448] INFO: 2 TBM Cores tbm08c (2 ON)
[10:51:11.448] INFO: TBM Core alpha (0): 7 registers set
[10:51:11.448] INFO: TBM Core beta (1): 7 registers set
[10:51:11.448] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:51:11.448] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.448] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.448] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.448] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.448] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.448] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.448] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.448] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.448] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.448] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.448] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.448] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.448] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.448] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.448] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.448] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:51:11.449] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:51:11.450] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[10:51:11.451] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:51:11.452] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:51:11.454] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35418112
[10:51:11.454] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x197dbd0
[10:51:11.454] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x171fe60
[10:51:11.454] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fcb81d94010
[10:51:11.454] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fcb87fff510
[10:51:11.454] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35426304 fPxarMemory = 0x7fcb81d94010
[10:51:11.455] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 378.6mA
[10:51:11.457] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 463.1mA
[10:51:11.457] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[10:51:11.953] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1d68880
[10:51:12.014] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[10:51:12.014] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[10:51:12.015] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[10:51:12.020] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[10:51:12.020] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[10:51:12.020] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[10:51:12.029] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[10:51:12.029] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[10:51:12.043] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[10:51:12.043] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[10:51:12.057] INFO: PixTestCmd::init()
[10:51:12.070] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[10:51:12.070] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[10:51:12.070] INFO: readGainPedestalParameters data/mp320/phCalibrationFitErr35_C0.dat .. data/mp320/phCalibrationFitErr35_C15.dat
[10:51:12.318] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[10:51:12.318] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[10:51:12.332] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[10:51:12.338] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[10:51:12.338] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[10:51:12.339] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[10:51:12.359] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[10:51:12.360] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[10:51:12.360] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[10:51:12.360] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[10:51:12.360] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[10:51:12.366] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[10:51:12.366] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[10:51:12.381] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[10:51:12.381] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[10:51:12.381] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[10:51:12.381] INFO: readReadbackCal: data/mp320/readbackCal_C0.dat .. data/mp320/readbackCal_C15.dat
[10:51:12.393] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[10:51:12.402] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[10:51:12.402] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[10:51:12.402] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[10:51:12.402] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[10:51:12.423] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[10:51:12.423] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[10:51:16.734] INFO: ######################################################################
[10:51:16.734] INFO: PixTestAlive::doTest()
[10:51:16.734] INFO: ######################################################################
[10:51:16.737] INFO: ----------------------------------------------------------------------
[10:51:16.737] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:51:16.737] INFO: ----------------------------------------------------------------------
[10:51:16.740] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:17.119] INFO: Expecting 41600 events.
[10:51:21.318] INFO: 41600 events read in total (3480ms).
[10:51:21.465] INFO: Test took 4725ms.
[10:51:21.477] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:21.477] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[10:51:21.477] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[10:51:21.749] INFO: PixTestAlive::aliveTest() done
[10:51:21.749] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:51:21.749] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 34 18 43 55 63 70 64 56 63 60 56 49 65 41 32 23
[10:51:21.777] INFO: ----------------------------------------------------------------------
[10:51:21.777] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:51:21.777] INFO: ----------------------------------------------------------------------
[10:51:21.779] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:22.160] INFO: Expecting 41600 events.
[10:51:25.090] INFO: 41600 events read in total (2215ms).
[10:51:25.090] INFO: Test took 3311ms.
[10:51:25.090] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:25.090] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[10:51:25.090] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[10:51:25.091] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:51:25.513] INFO: PixTestAlive::maskTest() done
[10:51:25.513] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:51:25.533] INFO: ----------------------------------------------------------------------
[10:51:25.533] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:51:25.533] INFO: ----------------------------------------------------------------------
[10:51:25.535] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:25.912] INFO: Expecting 41600 events.
[10:51:30.100] INFO: 41600 events read in total (3473ms).
[10:51:30.101] INFO: Test took 4566ms.
[10:51:30.112] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:30.112] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[10:51:30.112] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[10:51:30.482] INFO: PixTestAlive::addressDecodingTest() done
[10:51:30.482] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:51:30.482] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[10:51:32.595] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[10:51:32.595] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[10:51:32.595] INFO: ----------------------------------------------------------------------
[10:51:32.595] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:51:32.595] INFO: ----------------------------------------------------------------------
[10:51:32.734] INFO: Expecting 768 events.
[10:51:33.863] INFO: 768 events read in total (414ms).
[10:51:33.863] INFO: Test took 1263ms.
[10:51:33.869] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:34.666] INFO: Expecting 41600 events.
[10:51:38.127] INFO: 41600 events read in total (2934ms).
[10:51:38.132] INFO: Test took 4263ms.
[10:51:38.254] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:38.254] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 698127
[10:51:38.254] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[10:51:38.254] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:38.308] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:38.980] INFO: Expecting 41600 events.
[10:51:42.579] INFO: 41600 events read in total (3072ms).
[10:51:42.583] INFO: Test took 4275ms.
[10:51:42.702] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:42.702] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 706572
[10:51:42.702] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[10:51:42.703] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:42.757] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:43.422] INFO: Expecting 41600 events.
[10:51:47.042] INFO: 41600 events read in total (3094ms).
[10:51:47.047] INFO: Test took 4290ms.
[10:51:47.163] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:47.164] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 709017
[10:51:47.164] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[10:51:47.164] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:47.219] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:47.884] INFO: Expecting 41600 events.
[10:51:51.522] INFO: 41600 events read in total (3111ms).
[10:51:51.526] INFO: Test took 4307ms.
[10:51:51.644] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:51.644] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 709535
[10:51:51.644] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[10:51:51.645] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:51.699] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:52.364] INFO: Expecting 41600 events.
[10:51:55.999] INFO: 41600 events read in total (3108ms).
[10:51:56.004] INFO: Test took 4305ms.
[10:51:56.126] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:56.126] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708856
[10:51:56.126] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[10:51:56.126] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:56.182] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:56.851] INFO: Expecting 41600 events.
[10:52:00.500] INFO: 41600 events read in total (3123ms).
[10:52:00.505] INFO: Test took 4323ms.
[10:52:00.625] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:00.625] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708859
[10:52:00.625] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[10:52:00.625] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:00.680] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:01.345] INFO: Expecting 41600 events.
[10:52:04.987] INFO: 41600 events read in total (3116ms).
[10:52:04.991] INFO: Test took 4311ms.
[10:52:05.109] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:05.109] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708752
[10:52:05.109] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[10:52:05.109] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:05.164] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:05.829] INFO: Expecting 41600 events.
[10:52:09.479] INFO: 41600 events read in total (3123ms).
[10:52:09.484] INFO: Test took 4320ms.
[10:52:09.602] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:09.602] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 709564
[10:52:09.602] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[10:52:09.603] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:09.657] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:10.322] INFO: Expecting 41600 events.
[10:52:13.967] INFO: 41600 events read in total (3118ms).
[10:52:13.972] INFO: Test took 4315ms.
[10:52:14.092] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:14.092] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 709106
[10:52:14.092] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[10:52:14.093] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:14.147] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:14.812] INFO: Expecting 41600 events.
[10:52:18.480] INFO: 41600 events read in total (3142ms).
[10:52:18.485] INFO: Test took 4338ms.
[10:52:18.606] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:18.606] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708668
[10:52:18.606] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[10:52:18.606] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:18.660] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:19.325] INFO: Expecting 41600 events.
[10:52:22.966] INFO: 41600 events read in total (3114ms).
[10:52:22.971] INFO: Test took 4311ms.
[10:52:23.092] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:23.092] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 707873
[10:52:23.092] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[10:52:23.093] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:23.147] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:23.812] INFO: Expecting 41600 events.
[10:52:27.462] INFO: 41600 events read in total (3123ms).
[10:52:27.467] INFO: Test took 4320ms.
[10:52:27.587] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:27.587] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 709586
[10:52:27.587] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[10:52:27.587] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:27.642] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:28.307] INFO: Expecting 41600 events.
[10:52:31.950] INFO: 41600 events read in total (3116ms).
[10:52:31.955] INFO: Test took 4313ms.
[10:52:32.074] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:32.074] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 709732
[10:52:32.074] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[10:52:32.075] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:32.130] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:32.795] INFO: Expecting 41600 events.
[10:52:36.430] INFO: 41600 events read in total (3108ms).
[10:52:36.435] INFO: Test took 4305ms.
[10:52:36.553] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:36.553] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708350
[10:52:36.553] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[10:52:36.554] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:36.609] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:37.274] INFO: Expecting 41600 events.
[10:52:40.955] INFO: 41600 events read in total (3155ms).
[10:52:40.960] INFO: Test took 4351ms.
[10:52:41.078] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:41.078] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 709338
[10:52:41.078] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[10:52:41.079] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:41.134] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:41.799] INFO: Expecting 41600 events.
[10:52:45.452] INFO: 41600 events read in total (3127ms).
[10:52:45.456] INFO: Test took 4322ms.
[10:52:45.576] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:45.576] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708719
[10:52:45.576] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[10:52:45.576] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:45.632] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:46.297] INFO: Expecting 41600 events.
[10:52:49.937] INFO: 41600 events read in total (3113ms).
[10:52:49.942] INFO: Test took 4310ms.
[10:52:50.059] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:50.059] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 706924
[10:52:50.059] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[10:52:50.059] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:50.114] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:50.779] INFO: Expecting 41600 events.
[10:52:54.417] INFO: 41600 events read in total (3112ms).
[10:52:54.422] INFO: Test took 4308ms.
[10:52:54.541] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:54.542] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 707452
[10:52:54.542] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[10:52:54.542] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:54.597] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:55.264] INFO: Expecting 41600 events.
[10:52:58.849] INFO: 41600 events read in total (3059ms).
[10:52:58.853] INFO: Test took 4256ms.
[10:52:58.968] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:58.968] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 706119
[10:52:58.968] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[10:52:58.968] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:59.024] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:59.689] INFO: Expecting 41600 events.
[10:53:03.122] INFO: 41600 events read in total (2906ms).
[10:53:03.126] INFO: Test took 4102ms.
[10:53:03.240] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:03.240] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 697463
[10:53:03.240] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[10:53:03.241] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:53:03.517] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 137 eff = 0.999135
[10:53:03.517] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 160 eff = 0.999712
[10:53:03.517] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 171 eff = 0.998846
[10:53:03.517] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 173 eff = 0.998005
[10:53:03.517] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 155 eff = 0.998029
[10:53:03.518] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 164 eff = 0.997476
[10:53:03.518] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 152 eff = 0.997476
[10:53:03.518] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 161 eff = 0.997837
[10:53:03.518] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 192 eff = 0.997957
[10:53:03.519] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 155 eff = 0.997476
[10:53:03.519] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 150 eff = 0.99774
[10:53:03.519] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 156 eff = 0.99774
[10:53:03.519] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 173 eff = 0.997596
[10:53:03.519] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 157 eff = 0.998918
[10:53:03.519] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 157 eff = 0.999495
[10:53:03.520] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 131 eff = 0.999207
[10:53:07.723] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[10:53:07.723] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[10:53:07.723] INFO: ----------------------------------------------------------------------
[10:53:07.723] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:53:07.723] INFO: ----------------------------------------------------------------------
[10:53:07.723] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[10:53:07.723] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[10:53:07.723] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[10:53:07.723] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[10:53:07.723] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[10:53:07.723] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[10:53:07.723] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[10:53:07.723] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[10:53:07.723] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[10:53:07.723] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[10:53:07.723] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[10:53:07.723] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[10:53:07.741] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:53:08.410] INFO: Expecting 208000 events.
[10:53:23.548] INFO: 208000 events read in total (14611ms).
[10:53:23.565] INFO: Test took 15824ms.
[10:53:24.125] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:24.125] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3248792
[10:53:24.126] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[10:53:24.126] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:53:24.573] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:53:24.573] INFO: number of red-efficiency pixels: 232 122 274 468 489 502 495 446 379 530 505 457 473 262 138 223
[10:53:24.573] INFO: number of X-ray hits detected: 137644 92757 151111 237841 246931 254172 250005 205375 219601 260536 256319 239931 251018 156506 98753 123733
[10:53:24.573] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:53:24.573] INFO: number of Vcal hits detected: 207759 207871 207708 207481 207475 207440 207439 207514 207542 207412 207449 207498 207495 207721 207852 207772
[10:53:24.573] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.7 99.8 99.8 99.7 99.7 99.8 99.8 99.9 99.9 99.9
[10:53:24.573] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.7 99.8 99.8 99.7 99.7 99.8 99.8 99.9 99.9 99.9
[10:53:24.573] INFO: X-ray hit rate [MHz/cm2]: 40.3 27.2 44.3 69.7 72.4 74.5 73.3 60.2 64.4 76.4 75.1 70.3 73.6 45.9 28.9 36.3
[10:53:24.573] INFO: PixTestHighRate::doXPixelAlive() done
[10:53:24.618] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[10:53:24.618] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[10:53:24.618] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[10:53:24.618] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[10:53:24.618] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[10:53:24.618] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[10:53:24.618] INFO: PixTest:: pg_setup set to default.
[10:53:31.779] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[10:53:31.779] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C9_V0 -> highRate_xraymap_mod
[10:53:33.653] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:53:33.654] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 381.9mA
[10:53:33.654] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 464.7mA
[10:53:33.654] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[10:53:33.654] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:53:33.655] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:53:33.655] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:53:33.655] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:53:33.655] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:53:33.655] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:53:33.655] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:53:33.655] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:53:33.655] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:53:33.655] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:53:33.746] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:53:33.746] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:53:33.755] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:53:33.755] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:53:33.755] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:53:33.755] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:53:33.755] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:53:33.755] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:53:33.758] QUIET: Connection to board 43 closed.
[10:53:33.838] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[10:48:23.414] INFO: *** Welcome to pxar ***
[10:48:23.415] INFO: *** Today: 2016/06/09
[10:48:23.440] INFO: *** Version: v1.9.0-814-g7497
[10:48:23.440] INFO: readRocDacs: data/mp320/dacParameters35_C0.dat .. data/mp320/dacParameters35_C15.dat
[10:48:23.441] INFO: readTbmDacs: data/mp320/tbmParameters_C0a.dat .. data/mp320/tbmParameters_C0b.dat
[10:48:23.441] INFO: readMaskFile: data/mp320/defaultMaskFile.dat
[10:48:23.441] INFO: readTrimFile: data/mp320/trimParameters35_C0.dat .. data/mp320/trimParameters35_C15.dat
[10:48:23.592] INFO: clk: 4
[10:48:23.592] INFO: ctr: 4
[10:48:23.592] INFO: sda: 19
[10:48:23.592] INFO: tin: 9
[10:48:23.592] INFO: level: 15
[10:48:23.592] INFO: triggerdelay: 0
[10:48:23.592] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:48:23.592] INFO: Log level: DEBUG
[10:48:23.603] QUIET: Connection to board DTB_WRMNMR opened.
[10:48:23.606] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[10:48:23.609] INFO: RPC call hashes of host and DTB match: 398089610
[10:48:25.212] INFO: DUT info:
[10:48:25.212] INFO: The DUT currently contains the following objects:
[10:48:25.212] INFO: 2 TBM Cores tbm08c (2 ON)
[10:48:25.212] INFO: TBM Core alpha (0): 7 registers set
[10:48:25.212] INFO: TBM Core beta (1): 7 registers set
[10:48:25.212] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:48:25.212] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.212] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.212] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.212] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.212] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.212] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.212] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.212] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.212] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.212] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.212] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.212] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.212] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.212] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.212] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.212] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[10:48:25.213] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[10:48:25.214] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[10:48:25.215] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[10:48:25.216] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[10:48:25.216] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:48:25.216] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[10:48:25.216] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[10:48:25.216] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[10:48:25.216] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[10:48:25.216] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:48:25.216] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:48:25.218] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35745792
[10:48:25.218] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x24babd0
[10:48:25.218] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x225ce60
[10:48:25.218] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f8f09d94010
[10:48:25.218] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f8f0ffff510
[10:48:25.218] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35753984 fPxarMemory = 0x7f8f09d94010
[10:48:25.219] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 378.6mA
[10:48:25.220] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 463.1mA
[10:48:25.220] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[10:48:25.718] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x28a5810
[10:48:25.780] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[10:48:25.781] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[10:48:25.781] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[10:48:25.786] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[10:48:25.786] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[10:48:25.786] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[10:48:25.796] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[10:48:25.796] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[10:48:25.809] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[10:48:25.809] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[10:48:25.824] INFO: PixTestCmd::init()
[10:48:25.834] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[10:48:25.834] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[10:48:25.834] INFO: readGainPedestalParameters data/mp320/phCalibrationFitErr35_C0.dat .. data/mp320/phCalibrationFitErr35_C15.dat
[10:48:26.079] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[10:48:26.079] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[10:48:26.094] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[10:48:26.100] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[10:48:26.100] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[10:48:26.101] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[10:48:26.121] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[10:48:26.121] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[10:48:26.121] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[10:48:26.121] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[10:48:26.121] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[10:48:26.128] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[10:48:26.128] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[10:48:26.142] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[10:48:26.142] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[10:48:26.142] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[10:48:26.142] INFO: readReadbackCal: data/mp320/readbackCal_C0.dat .. data/mp320/readbackCal_C15.dat
[10:48:26.152] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[10:48:26.160] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[10:48:26.160] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[10:48:26.160] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[10:48:26.160] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[10:48:26.172] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[10:48:26.172] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[10:48:32.622] INFO: ######################################################################
[10:48:32.622] INFO: PixTestAlive::doTest()
[10:48:32.622] INFO: ######################################################################
[10:48:32.625] INFO: ----------------------------------------------------------------------
[10:48:32.625] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:48:32.625] INFO: ----------------------------------------------------------------------
[10:48:32.628] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:48:33.008] INFO: Expecting 41600 events.
[10:48:37.215] INFO: 41600 events read in total (3489ms).
[10:48:37.363] INFO: Test took 4735ms.
[10:48:37.374] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:37.375] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[10:48:37.375] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[10:48:37.647] INFO: PixTestAlive::aliveTest() done
[10:48:37.647] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:48:37.647] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 40 30 57 65 63 93 66 49 73 87 68 82 68 52 38 44
[10:48:37.672] INFO: ----------------------------------------------------------------------
[10:48:37.672] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:48:37.672] INFO: ----------------------------------------------------------------------
[10:48:37.674] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:48:38.051] INFO: Expecting 41600 events.
[10:48:40.986] INFO: 41600 events read in total (2220ms).
[10:48:40.986] INFO: Test took 3312ms.
[10:48:40.987] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:40.987] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[10:48:40.987] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[10:48:40.987] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:48:41.409] INFO: PixTestAlive::maskTest() done
[10:48:41.409] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:48:41.431] INFO: ----------------------------------------------------------------------
[10:48:41.431] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:48:41.431] INFO: ----------------------------------------------------------------------
[10:48:41.434] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:48:41.811] INFO: Expecting 41600 events.
[10:48:46.006] INFO: 41600 events read in total (3480ms).
[10:48:46.007] INFO: Test took 4573ms.
[10:48:46.019] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:46.019] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[10:48:46.019] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[10:48:46.390] INFO: PixTestAlive::addressDecodingTest() done
[10:48:46.390] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:48:46.390] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[10:48:50.331] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[10:48:50.331] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[10:48:50.331] INFO: ----------------------------------------------------------------------
[10:48:50.331] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:48:50.331] INFO: ----------------------------------------------------------------------
[10:48:50.470] INFO: Expecting 768 events.
[10:48:51.599] INFO: 768 events read in total (414ms).
[10:48:51.599] INFO: Test took 1263ms.
[10:48:51.605] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:48:52.402] INFO: Expecting 41600 events.
[10:48:56.089] INFO: 41600 events read in total (3160ms).
[10:48:56.094] INFO: Test took 4489ms.
[10:48:56.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:56.244] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 863984
[10:48:56.244] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[10:48:56.244] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:48:56.307] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:48:56.972] INFO: Expecting 41600 events.
[10:49:00.742] INFO: 41600 events read in total (3243ms).
[10:49:00.747] INFO: Test took 4440ms.
[10:49:00.888] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:00.888] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 870291
[10:49:00.888] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[10:49:00.888] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:49:00.954] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:49:01.619] INFO: Expecting 41600 events.
[10:49:05.434] INFO: 41600 events read in total (3288ms).
[10:49:05.439] INFO: Test took 4485ms.
[10:49:05.581] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:05.581] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 872041
[10:49:05.581] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[10:49:05.582] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:49:05.648] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:49:06.314] INFO: Expecting 41600 events.
[10:49:10.135] INFO: 41600 events read in total (3295ms).
[10:49:10.140] INFO: Test took 4492ms.
[10:49:10.281] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:10.281] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 872112
[10:49:10.281] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[10:49:10.281] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:49:10.347] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:49:11.013] INFO: Expecting 41600 events.
[10:49:14.846] INFO: 41600 events read in total (3306ms).
[10:49:14.851] INFO: Test took 4504ms.
[10:49:14.993] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:14.993] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 872969
[10:49:14.993] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[10:49:14.994] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:49:15.059] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:49:15.724] INFO: Expecting 41600 events.
[10:49:19.577] INFO: 41600 events read in total (3326ms).
[10:49:19.582] INFO: Test took 4523ms.
[10:49:19.724] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:19.724] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 873757
[10:49:19.724] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[10:49:19.725] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:49:19.791] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:49:20.457] INFO: Expecting 41600 events.
[10:49:24.295] INFO: 41600 events read in total (3312ms).
[10:49:24.301] INFO: Test took 4510ms.
[10:49:24.443] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:24.443] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 874069
[10:49:24.443] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[10:49:24.443] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:49:24.510] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:49:25.175] INFO: Expecting 41600 events.
[10:49:29.027] INFO: 41600 events read in total (3325ms).
[10:49:29.033] INFO: Test took 4523ms.
[10:49:29.177] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:29.177] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 873846
[10:49:29.177] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[10:49:29.177] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:49:29.243] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:49:29.908] INFO: Expecting 41600 events.
[10:49:33.751] INFO: 41600 events read in total (3316ms).
[10:49:33.756] INFO: Test took 4513ms.
[10:49:33.896] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:33.897] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 872376
[10:49:33.897] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[10:49:33.897] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:49:33.963] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:49:34.628] INFO: Expecting 41600 events.
[10:49:38.477] INFO: 41600 events read in total (3322ms).
[10:49:38.482] INFO: Test took 4519ms.
[10:49:38.628] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:38.628] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 874379
[10:49:38.628] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[10:49:38.629] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:49:38.695] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:49:39.360] INFO: Expecting 41600 events.
[10:49:43.204] INFO: 41600 events read in total (3317ms).
[10:49:43.209] INFO: Test took 4514ms.
[10:49:43.352] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:43.352] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 872445
[10:49:43.352] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[10:49:43.353] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:49:43.416] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:49:44.081] INFO: Expecting 41600 events.
[10:49:47.920] INFO: 41600 events read in total (3312ms).
[10:49:47.925] INFO: Test took 4509ms.
[10:49:48.065] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:48.065] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 873609
[10:49:48.065] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[10:49:48.065] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:49:48.130] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:49:48.795] INFO: Expecting 41600 events.
[10:49:52.644] INFO: 41600 events read in total (3322ms).
[10:49:52.649] INFO: Test took 4518ms.
[10:49:52.788] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:52.788] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 873588
[10:49:52.788] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[10:49:52.789] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:49:52.855] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:49:53.521] INFO: Expecting 41600 events.
[10:49:57.374] INFO: 41600 events read in total (3326ms).
[10:49:57.379] INFO: Test took 4523ms.
[10:49:57.522] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:57.522] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 874086
[10:49:57.522] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[10:49:57.522] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:49:57.589] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:49:58.256] INFO: Expecting 41600 events.
[10:50:02.114] INFO: 41600 events read in total (3331ms).
[10:50:02.119] INFO: Test took 4530ms.
[10:50:02.261] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:02.261] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 871789
[10:50:02.261] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[10:50:02.261] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:50:02.330] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:50:02.996] INFO: Expecting 41600 events.
[10:50:06.861] INFO: 41600 events read in total (3338ms).
[10:50:06.867] INFO: Test took 4537ms.
[10:50:07.030] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:07.030] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 872005
[10:50:07.030] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[10:50:07.031] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:50:07.098] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:50:07.764] INFO: Expecting 41600 events.
[10:50:11.611] INFO: 41600 events read in total (3320ms).
[10:50:11.616] INFO: Test took 4518ms.
[10:50:11.758] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:11.758] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 872512
[10:50:11.758] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[10:50:11.758] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:50:11.824] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:50:12.490] INFO: Expecting 41600 events.
[10:50:16.345] INFO: 41600 events read in total (3329ms).
[10:50:16.350] INFO: Test took 4526ms.
[10:50:16.492] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:16.492] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 871462
[10:50:16.492] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[10:50:16.492] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:50:16.558] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:50:17.223] INFO: Expecting 41600 events.
[10:50:21.016] INFO: 41600 events read in total (3266ms).
[10:50:21.021] INFO: Test took 4463ms.
[10:50:21.161] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:21.162] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 869824
[10:50:21.162] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[10:50:21.162] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:50:21.229] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:50:21.894] INFO: Expecting 41600 events.
[10:50:25.521] INFO: 41600 events read in total (3101ms).
[10:50:25.527] INFO: Test took 4298ms.
[10:50:25.666] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:25.666] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 862006
[10:50:25.666] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[10:50:25.667] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:50:25.955] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 161 eff = 0.998894
[10:50:25.955] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 140 eff = 0.999183
[10:50:25.956] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 174 eff = 0.997981
[10:50:25.956] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 169 eff = 0.99625
[10:50:25.956] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 158 eff = 0.996394
[10:50:25.956] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 161 eff = 0.995577
[10:50:25.957] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 149 eff = 0.99601
[10:50:25.957] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 161 eff = 0.996803
[10:50:25.957] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 189 eff = 0.996635
[10:50:25.957] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 155 eff = 0.995288
[10:50:25.958] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 149 eff = 0.995721
[10:50:25.958] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 158 eff = 0.995962
[10:50:25.958] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 173 eff = 0.99601
[10:50:25.958] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 156 eff = 0.998149
[10:50:25.959] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 147 eff = 0.999087
[10:50:25.959] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 149 eff = 0.998894
[10:50:34.187] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[10:50:34.187] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[10:50:34.187] INFO: ----------------------------------------------------------------------
[10:50:34.187] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:50:34.187] INFO: ----------------------------------------------------------------------
[10:50:34.187] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[10:50:34.187] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[10:50:34.187] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[10:50:34.187] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[10:50:34.187] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[10:50:34.187] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[10:50:34.187] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[10:50:34.187] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[10:50:34.187] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[10:50:34.187] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[10:50:34.187] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[10:50:34.187] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[10:50:34.205] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:50:34.875] INFO: Expecting 208000 events.
[10:50:51.607] INFO: 208000 events read in total (16206ms).
[10:50:51.627] INFO: Test took 17422ms.
[10:50:52.318] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:52.318] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4049135
[10:50:52.318] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[10:50:52.318] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:50:52.817] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:50:52.818] INFO: number of red-efficiency pixels: 279 210 368 746 680 792 732 559 618 871 798 745 745 371 217 262
[10:50:52.818] INFO: number of X-ray hits detected: 172429 114922 188772 297959 309855 317211 314789 257044 273557 325581 321067 300111 314722 195426 123876 155255
[10:50:52.818] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:50:52.818] INFO: number of Vcal hits detected: 207708 207783 207591 207163 207218 207080 207134 207355 207257 206987 207102 207158 207138 207589 207773 207725
[10:50:52.818] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.6 99.7 99.6 99.6 99.7 99.7 99.5 99.6 99.6 99.6 99.8 99.9 99.9
[10:50:52.818] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.6 99.6 99.6 99.6 99.7 99.6 99.5 99.6 99.6 99.6 99.8 99.9 99.9
[10:50:52.818] INFO: X-ray hit rate [MHz/cm2]: 50.5 33.7 55.3 87.3 90.8 93.0 92.3 75.3 80.2 95.4 94.1 88.0 92.2 57.3 36.3 45.5
[10:50:52.818] INFO: PixTestHighRate::doXPixelAlive() done
[10:50:52.862] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[10:50:52.862] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[10:50:52.862] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[10:50:52.862] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[10:50:52.862] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[10:50:52.862] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[10:50:52.862] INFO: PixTest:: pg_setup set to default.
[10:51:02.083] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[10:51:02.083] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C9_V0 -> highRate_xraymap_mod
[10:51:03.923] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:51:03.923] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 381.9mA
[10:51:03.924] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 464.7mA
[10:51:03.924] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.5 C
[10:51:03.924] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:51:03.924] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:51:03.924] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:51:03.924] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:51:03.924] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:51:03.925] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:51:03.925] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:51:03.925] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:51:03.925] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:51:03.925] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:51:04.018] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:51:04.019] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:51:04.028] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:51:04.028] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:51:04.028] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:51:04.028] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:51:04.028] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:51:04.028] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:51:04.031] QUIET: Connection to board 43 closed.
[10:51:04.110] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master