Test Date: 1973-11-29 16:33
Analysis date: 2016-06-09 11:15
Logfile
hrData_40.log
[16:42:52.201] INFO: *** Welcome to pxar ***
[16:42:52.201] INFO: *** Today: 2016/05/25
[16:42:52.224] INFO: *** Version: v1.9.0-805-geb36
[16:42:52.224] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/dacParameters35_C15.dat
[16:42:52.225] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/tbmParameters_C0b.dat
[16:42:52.225] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/defaultMaskFile.dat
[16:42:52.225] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/trimParameters35_C15.dat
[16:42:52.374] INFO: clk: 4
[16:42:52.374] INFO: ctr: 4
[16:42:52.374] INFO: sda: 19
[16:42:52.374] INFO: tin: 9
[16:42:52.374] INFO: level: 15
[16:42:52.374] INFO: triggerdelay: 0
[16:42:52.374] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:42:52.374] INFO: Log level: DEBUG
[16:42:52.385] QUIET: Connection to board DTB_WRPRHI opened.
[16:42:52.388] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:42:52.391] INFO: RPC call hashes of host and DTB match: 398089610
[16:42:53.990] INFO: DUT info:
[16:42:53.990] INFO: The DUT currently contains the following objects:
[16:42:53.990] INFO: 2 TBM Cores tbm08c (2 ON)
[16:42:53.990] INFO: TBM Core alpha (0): 7 registers set
[16:42:53.990] INFO: TBM Core beta (1): 7 registers set
[16:42:53.990] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:42:53.990] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.990] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.991] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.991] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.991] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.991] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.991] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.991] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.991] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.991] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.991] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.991] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.991] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.991] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.991] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.991] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:42:53.991] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:42:53.992] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:42:53.993] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:42:53.996] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31649792
[16:42:53.996] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x26f2f10
[16:42:53.996] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x266ce30
[16:42:53.996] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f478dd94010
[16:42:53.996] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f4793fff510
[16:42:53.996] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31657984 fPxarMemory = 0x7f478dd94010
[16:42:53.997] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381mA
[16:42:53.998] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[16:42:53.998] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:42:54.485] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2cb59c0
[16:42:54.545] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:42:54.546] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:42:54.546] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:42:54.551] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:42:54.551] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:42:54.551] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:42:54.560] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:42:54.560] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:42:54.573] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:42:54.574] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:42:54.593] INFO: PixTestCmd::init()
[16:42:54.612] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:42:54.612] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:42:54.612] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mo109/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/phCalibrationFitErr35_C15.dat
[16:42:54.857] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:42:54.857] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:42:54.870] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:42:54.876] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:42:54.876] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[16:42:54.876] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:42:54.897] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:42:54.897] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:42:54.897] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:42:54.897] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:42:54.897] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:42:54.903] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:42:54.904] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:42:54.918] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:42:54.918] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:42:54.918] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:42:54.918] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/readbackCal_C15.dat
[16:42:54.928] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:42:54.935] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:42:54.935] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:42:54.935] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:42:54.935] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:42:54.947] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:42:54.947] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:43:01.515] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[16:43:04.142] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[16:43:06.731] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[16:43:06.731] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[16:43:06.731] INFO: ----------------------------------------------------------------------
[16:43:06.731] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[16:43:06.731] INFO: ----------------------------------------------------------------------
[16:43:07.703] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[16:43:20.662] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[16:43:20.666] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[16:43:45.350] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1276801 events.
[16:43:50.923] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1276801, pixels seen in all events: 4930157
[16:43:51.020] INFO: Resuming triggers.
[16:44:03.984] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[16:44:04.015] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[16:44:28.656] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1277145 events.
[16:44:34.259] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1277145, pixels seen in all events: 4925533
[16:44:34.414] INFO: Resuming triggers.
[16:44:47.377] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[16:44:47.406] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[16:45:12.732] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1277060 events.
[16:45:18.242] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1277060, pixels seen in all events: 4926668
[16:45:18.459] INFO: Resuming triggers.
[16:45:31.435] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[16:45:31.463] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[16:45:56.263] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1278339 events.
[16:46:01.809] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1278339, pixels seen in all events: 4928829
[16:46:02.029] INFO: Resuming triggers.
[16:46:10.249] INFO: data taking finished, elapsed time: 60 seconds.
[16:46:10.445] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[16:46:26.191] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 809715 events.
[16:46:29.698] DEBUG: <PixTestXray.cc/processData:L809> # events read: 809715, pixels seen in all events: 3127486
[16:46:29.763] INFO: PixTest:: pg_setup set to default.
[16:46:29.836] INFO: PixTestXray::doPhRun() done
[16:46:39.491] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:46:39.491] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hMap_02ma_C12_V0 -> hMap_02ma_mod
[16:46:41.027] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:46:41.028] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[16:46:41.029] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[16:46:41.029] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:46:41.029] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:46:41.029] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:46:41.029] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:46:41.029] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:46:41.029] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:46:41.029] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:46:41.029] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:46:41.029] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:46:41.029] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:46:41.029] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[16:46:41.030] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:46:41.030] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:46:41.030] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:46:41.030] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:46:41.030] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:46:41.030] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:46:41.030] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:46:41.030] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:46:41.309] QUIET: Connection to board 58 closed.
[16:46:41.389] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[16:46:47.002] INFO: *** Welcome to pxar ***
[16:46:47.002] INFO: *** Today: 2016/05/25
[16:46:47.025] INFO: *** Version: v1.9.0-805-geb36
[16:46:47.025] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/dacParameters35_C15.dat
[16:46:47.026] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/tbmParameters_C0b.dat
[16:46:47.026] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/defaultMaskFile.dat
[16:46:47.026] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/trimParameters35_C15.dat
[16:46:47.175] INFO: clk: 4
[16:46:47.175] INFO: ctr: 4
[16:46:47.175] INFO: sda: 19
[16:46:47.175] INFO: tin: 9
[16:46:47.175] INFO: level: 15
[16:46:47.175] INFO: triggerdelay: 0
[16:46:47.175] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:46:47.175] INFO: Log level: DEBUG
[16:46:47.186] QUIET: Connection to board DTB_WRPRHI opened.
[16:46:47.189] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:46:47.192] INFO: RPC call hashes of host and DTB match: 398089610
[16:46:48.792] INFO: DUT info:
[16:46:48.792] INFO: The DUT currently contains the following objects:
[16:46:48.792] INFO: 2 TBM Cores tbm08c (2 ON)
[16:46:48.792] INFO: TBM Core alpha (0): 7 registers set
[16:46:48.792] INFO: TBM Core beta (1): 7 registers set
[16:46:48.792] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:46:48.792] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.792] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.792] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.792] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.792] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.792] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.792] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.792] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.792] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.792] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.792] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.792] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.792] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.792] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.792] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.792] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:46:48.793] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:46:48.794] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:46:48.795] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:46:48.797] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33157120
[16:46:48.797] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1198f10
[16:46:48.797] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1112e30
[16:46:48.797] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f20add94010
[16:46:48.797] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f20b3fff510
[16:46:48.797] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33165312 fPxarMemory = 0x7f20add94010
[16:46:48.799] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381.9mA
[16:46:48.800] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[16:46:48.800] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:46:49.287] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x175b950
[16:46:49.347] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:46:49.347] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:46:49.347] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:46:49.353] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:46:49.353] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:46:49.353] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:46:49.364] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:46:49.364] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:46:49.377] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:46:49.377] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:46:49.388] INFO: PixTestCmd::init()
[16:46:49.403] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:46:49.403] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:46:49.403] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mo109/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/phCalibrationFitErr35_C15.dat
[16:46:49.648] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:46:49.648] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:46:49.662] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:46:49.668] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:46:49.668] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[16:46:49.668] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:46:49.693] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:46:49.693] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:46:49.693] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:46:49.693] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:46:49.693] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:46:49.700] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:46:49.700] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:46:49.718] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:46:49.718] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:46:49.719] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:46:49.719] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/readbackCal_C15.dat
[16:46:49.728] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:46:49.736] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:46:49.736] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:46:49.736] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:46:49.736] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:46:49.753] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:46:49.753] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:46:58.558] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[16:47:04.686] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 55
[16:47:06.723] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[16:47:06.723] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[16:47:06.723] INFO: ----------------------------------------------------------------------
[16:47:06.723] INFO: PixTestXray::doPhRun() fParRunSeconds = 55
[16:47:06.723] INFO: ----------------------------------------------------------------------
[16:47:07.696] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 55 seconds, fEventsMax = 5500000
[16:47:15.316] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[16:47:15.320] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[16:47:38.249] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 750836 events.
[16:47:50.267] DEBUG: <PixTestXray.cc/processData:L809> # events read: 750836, pixels seen in all events: 11243808
[16:47:50.508] INFO: Resuming triggers.
[16:47:58.137] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[16:47:58.165] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[16:48:21.107] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 751490 events.
[16:48:33.106] DEBUG: <PixTestXray.cc/processData:L809> # events read: 751490, pixels seen in all events: 11233916
[16:48:33.301] INFO: Resuming triggers.
[16:48:40.938] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[16:48:40.995] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[16:49:03.953] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 752289 events.
[16:49:15.966] DEBUG: <PixTestXray.cc/processData:L809> # events read: 752289, pixels seen in all events: 11225005
[16:49:16.157] INFO: Resuming triggers.
[16:49:23.795] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[16:49:23.857] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[16:49:46.738] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 752449 events.
[16:49:58.754] DEBUG: <PixTestXray.cc/processData:L809> # events read: 752449, pixels seen in all events: 11222075
[16:49:58.940] INFO: Resuming triggers.
[16:50:06.580] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[16:50:06.618] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[16:50:29.423] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 752611 events.
[16:50:41.388] DEBUG: <PixTestXray.cc/processData:L809> # events read: 752611, pixels seen in all events: 11221188
[16:50:41.581] INFO: Resuming triggers.
[16:50:49.222] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[16:50:49.282] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[16:51:12.191] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 752718 events.
[16:51:24.153] DEBUG: <PixTestXray.cc/processData:L809> # events read: 752718, pixels seen in all events: 11219451
[16:51:24.346] INFO: Resuming triggers.
[16:51:31.992] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[16:51:32.054] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[16:51:54.837] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 753164 events.
[16:52:06.779] DEBUG: <PixTestXray.cc/processData:L809> # events read: 753164, pixels seen in all events: 11213659
[16:52:06.974] INFO: Resuming triggers.
[16:52:08.593] INFO: data taking finished, elapsed time: 55 seconds.
[16:52:08.789] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[16:52:13.774] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 159489 events.
[16:52:16.303] DEBUG: <PixTestXray.cc/processData:L809> # events read: 159489, pixels seen in all events: 2373077
[16:52:16.362] INFO: PixTest:: pg_setup set to default.
[16:52:16.398] INFO: PixTestXray::doPhRun() done
[16:52:23.555] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:52:23.556] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hMap_06ma_C12_V0 -> hMap_06ma_mod
[16:52:24.867] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:52:24.868] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[16:52:24.869] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.1mA
[16:52:24.869] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.5 C
[16:52:24.869] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:52:24.869] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:52:24.869] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:52:24.869] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:52:24.869] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:52:24.870] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:52:24.870] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:52:24.870] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:52:24.870] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:52:24.870] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[16:52:24.870] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:52:24.870] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:52:24.870] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:52:24.870] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:52:24.870] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:52:24.870] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:52:24.870] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:52:24.870] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:52:25.186] QUIET: Connection to board 58 closed.
[16:52:25.266] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[16:34:17.493] INFO: *** Welcome to pxar ***
[16:34:17.493] INFO: *** Today: 2016/05/25
[16:34:17.517] INFO: *** Version: v1.9.0-805-geb36
[16:34:17.517] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/dacParameters35_C15.dat
[16:34:17.518] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/tbmParameters_C0b.dat
[16:34:17.518] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/defaultMaskFile.dat
[16:34:17.518] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/trimParameters35_C15.dat
[16:34:17.670] INFO: clk: 4
[16:34:17.670] INFO: ctr: 4
[16:34:17.670] INFO: sda: 19
[16:34:17.670] INFO: tin: 9
[16:34:17.670] INFO: level: 15
[16:34:17.670] INFO: triggerdelay: 0
[16:34:17.670] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:34:17.670] INFO: Log level: DEBUG
[16:34:17.681] QUIET: Connection to board DTB_WRPRHI opened.
[16:34:17.684] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:34:17.687] INFO: RPC call hashes of host and DTB match: 398089610
[16:34:19.287] INFO: DUT info:
[16:34:19.287] INFO: The DUT currently contains the following objects:
[16:34:19.287] INFO: 2 TBM Cores tbm08c (2 ON)
[16:34:19.287] INFO: TBM Core alpha (0): 7 registers set
[16:34:19.287] INFO: TBM Core beta (1): 7 registers set
[16:34:19.287] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:34:19.287] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:34:19.287] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:34:19.287] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:34:19.288] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:34:19.289] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:34:19.290] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:34:19.292] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34865152
[16:34:19.292] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1857f10
[16:34:19.292] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x17d1e30
[16:34:19.292] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f780dd94010
[16:34:19.292] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f7813fff510
[16:34:19.292] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34873344 fPxarMemory = 0x7f780dd94010
[16:34:19.293] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381mA
[16:34:19.295] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[16:34:19.295] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[16:34:19.783] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1e1a930
[16:34:19.843] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:34:19.843] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:34:19.843] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:34:19.849] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:34:19.849] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:34:19.849] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:34:19.857] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:34:19.857] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:34:19.867] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:34:19.867] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:34:19.898] INFO: PixTestCmd::init()
[16:34:19.903] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:34:19.903] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:34:19.904] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mo109/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/phCalibrationFitErr35_C15.dat
[16:34:20.146] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:34:20.146] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:34:20.160] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:34:20.166] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:34:20.167] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[16:34:20.167] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:34:20.189] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:34:20.189] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:34:20.189] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:34:20.189] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:34:20.189] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:34:20.195] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:34:20.195] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:34:20.209] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:34:20.210] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:34:20.210] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:34:20.210] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/readbackCal_C15.dat
[16:34:20.220] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:34:20.228] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:34:20.228] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:34:20.228] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:34:20.228] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:34:20.244] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:34:20.244] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:34:30.654] INFO: ######################################################################
[16:34:30.654] INFO: PixTestAlive::doTest()
[16:34:30.655] INFO: ######################################################################
[16:34:30.658] INFO: ----------------------------------------------------------------------
[16:34:30.658] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:34:30.658] INFO: ----------------------------------------------------------------------
[16:34:30.660] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:34:31.036] INFO: Expecting 41600 events.
[16:34:35.294] INFO: 41600 events read in total (3541ms).
[16:34:35.443] INFO: Test took 4783ms.
[16:34:35.452] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:34:35.452] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:34:35.452] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:34:35.724] INFO: PixTestAlive::aliveTest() done
[16:34:35.724] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:34:35.724] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 16 18 20 45 42 37 39 26 38 38 38 37 41 27 18 15
[16:34:35.747] INFO: ----------------------------------------------------------------------
[16:34:35.747] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:34:35.747] INFO: ----------------------------------------------------------------------
[16:34:35.749] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:34:36.123] INFO: Expecting 41600 events.
[16:34:39.177] INFO: 41600 events read in total (2339ms).
[16:34:39.178] INFO: Test took 3429ms.
[16:34:39.178] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:34:39.178] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:34:39.178] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:34:39.178] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:34:39.602] INFO: PixTestAlive::maskTest() done
[16:34:39.602] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:34:39.620] INFO: ----------------------------------------------------------------------
[16:34:39.620] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:34:39.620] INFO: ----------------------------------------------------------------------
[16:34:39.622] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:34:39.996] INFO: Expecting 41600 events.
[16:34:44.239] INFO: 41600 events read in total (3528ms).
[16:34:44.240] INFO: Test took 4618ms.
[16:34:44.251] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:34:44.251] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:34:44.251] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:34:44.623] INFO: PixTestAlive::addressDecodingTest() done
[16:34:44.623] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:34:44.623] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[16:35:02.595] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:35:02.595] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[16:35:02.595] INFO: ----------------------------------------------------------------------
[16:35:02.595] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:35:02.595] INFO: ----------------------------------------------------------------------
[16:35:02.734] INFO: Expecting 768 events.
[16:35:03.866] INFO: 768 events read in total (417ms).
[16:35:03.867] INFO: Test took 1266ms.
[16:35:03.872] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:35:04.670] INFO: Expecting 41600 events.
[16:35:07.875] INFO: 41600 events read in total (2679ms).
[16:35:07.879] INFO: Test took 4007ms.
[16:35:07.961] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:07.961] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 474723
[16:35:07.961] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:35:07.962] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:35:08.000] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:35:08.665] INFO: Expecting 41600 events.
[16:35:11.969] INFO: 41600 events read in total (2777ms).
[16:35:11.973] INFO: Test took 3973ms.
[16:35:12.052] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:12.052] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 481589
[16:35:12.052] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:35:12.052] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:35:12.092] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:35:12.757] INFO: Expecting 41600 events.
[16:35:16.101] INFO: 41600 events read in total (2817ms).
[16:35:16.105] INFO: Test took 4013ms.
[16:35:16.185] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:16.185] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 482179
[16:35:16.185] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:35:16.185] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:35:16.226] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:35:16.892] INFO: Expecting 41600 events.
[16:35:20.231] INFO: 41600 events read in total (2812ms).
[16:35:20.235] INFO: Test took 4009ms.
[16:35:20.315] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:20.315] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 482003
[16:35:20.315] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:35:20.315] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:35:20.357] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:35:21.023] INFO: Expecting 41600 events.
[16:35:24.367] INFO: 41600 events read in total (2817ms).
[16:35:24.370] INFO: Test took 4013ms.
[16:35:24.449] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:24.449] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 482423
[16:35:24.449] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:35:24.450] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:35:24.490] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:35:25.158] INFO: Expecting 41600 events.
[16:35:28.505] INFO: 41600 events read in total (2821ms).
[16:35:28.510] INFO: Test took 4020ms.
[16:35:28.589] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:28.589] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 482588
[16:35:28.589] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:35:28.589] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:35:28.629] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:35:29.294] INFO: Expecting 41600 events.
[16:35:32.644] INFO: 41600 events read in total (2824ms).
[16:35:32.648] INFO: Test took 4019ms.
[16:35:32.728] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:32.728] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 482738
[16:35:32.728] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:35:32.729] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:35:32.769] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:35:33.434] INFO: Expecting 41600 events.
[16:35:36.791] INFO: 41600 events read in total (2831ms).
[16:35:36.795] INFO: Test took 4026ms.
[16:35:36.875] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:36.875] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483039
[16:35:36.875] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:35:36.876] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:35:36.915] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:35:37.580] INFO: Expecting 41600 events.
[16:35:40.938] INFO: 41600 events read in total (2831ms).
[16:35:40.942] INFO: Test took 4027ms.
[16:35:41.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:41.022] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483383
[16:35:41.022] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:35:41.023] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:35:41.064] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:35:41.730] INFO: Expecting 41600 events.
[16:35:45.083] INFO: 41600 events read in total (2826ms).
[16:35:45.087] INFO: Test took 4023ms.
[16:35:45.168] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:45.168] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 482577
[16:35:45.168] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:35:45.168] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:35:45.209] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:35:45.875] INFO: Expecting 41600 events.
[16:35:49.230] INFO: 41600 events read in total (2828ms).
[16:35:49.234] INFO: Test took 4025ms.
[16:35:49.315] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:49.315] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 481628
[16:35:49.315] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:35:49.315] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:35:49.357] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:35:50.023] INFO: Expecting 41600 events.
[16:35:53.381] INFO: 41600 events read in total (2831ms).
[16:35:53.385] INFO: Test took 4028ms.
[16:35:53.466] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:53.466] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 484364
[16:35:53.466] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:35:53.466] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:35:53.507] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:35:54.174] INFO: Expecting 41600 events.
[16:35:57.531] INFO: 41600 events read in total (2831ms).
[16:35:57.535] INFO: Test took 4028ms.
[16:35:57.614] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:57.614] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 482885
[16:35:57.614] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:35:57.614] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:35:57.654] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:35:58.319] INFO: Expecting 41600 events.
[16:36:01.689] INFO: 41600 events read in total (2843ms).
[16:36:01.693] INFO: Test took 4039ms.
[16:36:01.772] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:01.772] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483150
[16:36:01.772] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:36:01.773] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:36:01.813] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:36:02.478] INFO: Expecting 41600 events.
[16:36:05.851] INFO: 41600 events read in total (2846ms).
[16:36:05.855] INFO: Test took 4042ms.
[16:36:05.934] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:05.934] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483859
[16:36:05.934] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:36:05.935] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:36:05.974] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:36:06.640] INFO: Expecting 41600 events.
[16:36:10.001] INFO: 41600 events read in total (2834ms).
[16:36:10.004] INFO: Test took 4030ms.
[16:36:10.083] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:10.083] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 482821
[16:36:10.083] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:36:10.083] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:36:10.123] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:36:10.788] INFO: Expecting 41600 events.
[16:36:14.139] INFO: 41600 events read in total (2824ms).
[16:36:14.143] INFO: Test took 4020ms.
[16:36:14.222] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:14.222] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 482224
[16:36:14.222] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:36:14.223] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:36:14.263] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:36:14.929] INFO: Expecting 41600 events.
[16:36:18.296] INFO: 41600 events read in total (2840ms).
[16:36:18.299] INFO: Test took 4036ms.
[16:36:18.380] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:18.380] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 481503
[16:36:18.380] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:36:18.381] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:36:18.422] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:36:19.088] INFO: Expecting 41600 events.
[16:36:22.443] INFO: 41600 events read in total (2828ms).
[16:36:22.447] INFO: Test took 4025ms.
[16:36:22.528] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:22.528] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 482513
[16:36:22.528] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:36:22.529] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:36:22.568] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:36:23.234] INFO: Expecting 41600 events.
[16:36:26.500] INFO: 41600 events read in total (2739ms).
[16:36:26.504] INFO: Test took 3936ms.
[16:36:26.583] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:26.583] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 478450
[16:36:26.583] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:36:26.583] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:36:26.845] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 168 eff = 0.999808
[16:36:26.845] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 169 eff = 0.999712
[16:36:26.845] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 158 eff = 0.999447
[16:36:26.846] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 165 eff = 0.999038
[16:36:26.846] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 166 eff = 0.998894
[16:36:26.846] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 156 eff = 0.998678
[16:36:26.846] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 168 eff = 0.998918
[16:36:26.847] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 156 eff = 0.999135
[16:36:26.847] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 149 eff = 0.999207
[16:36:26.847] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 182 eff = 0.99887
[16:36:26.847] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 164 eff = 0.999303
[16:36:26.848] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 150 eff = 0.99887
[16:36:26.848] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 148 eff = 0.99899
[16:36:26.848] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 138 eff = 0.999495
[16:36:26.848] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 154 eff = 0.999639
[16:36:26.849] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 162 eff = 0.999663
[16:36:30.611] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:36:30.611] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[16:36:30.611] INFO: ----------------------------------------------------------------------
[16:36:30.611] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:36:30.611] INFO: ----------------------------------------------------------------------
[16:36:30.612] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[16:36:30.612] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[16:36:30.612] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[16:36:30.612] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[16:36:30.612] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[16:36:30.612] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[16:36:30.612] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[16:36:30.612] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[16:36:30.612] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[16:36:30.612] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[16:36:30.612] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[16:36:30.612] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[16:36:30.627] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:36:31.292] INFO: Expecting 208000 events.
[16:36:43.995] INFO: 208000 events read in total (12176ms).
[16:36:44.007] INFO: Test took 13380ms.
[16:36:44.434] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:44.434] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2130108
[16:36:44.434] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:36:44.434] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:36:44.802] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:36:44.802] INFO: number of red-efficiency pixels: 95 72 158 245 290 289 298 261 208 256 244 279 293 155 78 107
[16:36:44.802] INFO: number of X-ray hits detected: 78854 60073 99968 155058 164335 164410 171075 135798 142336 172425 164249 153431 153634 104768 63863 79271
[16:36:44.802] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:36:44.802] INFO: number of Vcal hits detected: 207903 207926 207835 207739 207697 207696 207685 207727 207782 207732 207748 207707 207692 207841 207919 207891
[16:36:44.802] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[16:36:44.802] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[16:36:44.802] INFO: X-ray hit rate [MHz/cm2]: 23.1 17.6 29.3 45.4 48.2 48.2 50.1 39.8 41.7 50.5 48.1 45.0 45.0 30.7 18.7 23.2
[16:36:44.802] INFO: PixTestHighRate::doXPixelAlive() done
[16:36:44.847] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[16:36:44.847] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[16:36:44.847] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[16:36:44.847] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[16:36:44.847] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[16:36:44.847] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[16:36:44.847] INFO: PixTest:: pg_setup set to default.
[16:37:40.659] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:37:40.659] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[16:37:42.731] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:37:42.732] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[16:37:42.733] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 465.5mA
[16:37:42.733] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[16:37:42.733] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:37:42.733] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:37:42.733] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:37:42.733] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:37:42.733] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:37:42.733] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:37:42.733] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:37:42.733] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:37:42.733] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:37:42.734] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[16:37:42.819] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:37:42.819] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:37:42.828] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:37:42.828] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:37:42.828] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:37:42.828] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:37:42.828] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:37:42.828] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:37:42.831] QUIET: Connection to board 58 closed.
[16:37:42.910] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[16:30:04.964] INFO: *** Welcome to pxar ***
[16:30:04.964] INFO: *** Today: 2016/05/25
[16:30:04.989] INFO: *** Version: v1.9.0-805-geb36
[16:30:04.989] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/dacParameters35_C15.dat
[16:30:04.990] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/tbmParameters_C0b.dat
[16:30:04.990] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/defaultMaskFile.dat
[16:30:04.990] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/trimParameters35_C15.dat
[16:30:05.138] INFO: clk: 4
[16:30:05.139] INFO: ctr: 4
[16:30:05.139] INFO: sda: 19
[16:30:05.139] INFO: tin: 9
[16:30:05.139] INFO: level: 15
[16:30:05.139] INFO: triggerdelay: 0
[16:30:05.139] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:30:05.139] INFO: Log level: DEBUG
[16:30:05.150] QUIET: Connection to board DTB_WRPRHI opened.
[16:30:05.153] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:30:05.156] INFO: RPC call hashes of host and DTB match: 398089610
[16:30:06.755] INFO: DUT info:
[16:30:06.755] INFO: The DUT currently contains the following objects:
[16:30:06.756] INFO: 2 TBM Cores tbm08c (2 ON)
[16:30:06.756] INFO: TBM Core alpha (0): 7 registers set
[16:30:06.756] INFO: TBM Core beta (1): 7 registers set
[16:30:06.756] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:30:06.756] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:30:06.756] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:30:06.757] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:30:06.758] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:30:06.759] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:30:06.759] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:30:06.759] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:30:06.759] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:30:06.759] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:30:06.759] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:30:06.759] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:30:06.759] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:30:06.761] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35078144
[16:30:06.761] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x11d0f10
[16:30:06.761] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x114ae30
[16:30:06.761] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f8479d94010
[16:30:06.761] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f847ffff510
[16:30:06.761] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35086336 fPxarMemory = 0x7f8479d94010
[16:30:06.762] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381mA
[16:30:06.763] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[16:30:06.763] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[16:30:07.252] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1793940
[16:30:07.314] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:30:07.314] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:30:07.314] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:30:07.320] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:30:07.320] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:30:07.320] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:30:07.329] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:30:07.329] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:30:07.335] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:30:07.336] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:30:07.354] INFO: PixTestCmd::init()
[16:30:07.359] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:30:07.359] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:30:07.359] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mo109/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/phCalibrationFitErr35_C15.dat
[16:30:07.600] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:30:07.600] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:30:07.614] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:30:07.621] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:30:07.621] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[16:30:07.621] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:30:07.643] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:30:07.643] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:30:07.644] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:30:07.644] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:30:07.644] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:30:07.650] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:30:07.650] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:30:07.665] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:30:07.665] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:30:07.665] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:30:07.665] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/readbackCal_C15.dat
[16:30:07.675] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:30:07.683] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:30:07.683] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:30:07.683] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:30:07.683] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:30:07.700] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:30:07.700] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:30:20.254] INFO: ######################################################################
[16:30:20.254] INFO: PixTestAlive::doTest()
[16:30:20.254] INFO: ######################################################################
[16:30:20.258] INFO: ----------------------------------------------------------------------
[16:30:20.258] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:30:20.258] INFO: ----------------------------------------------------------------------
[16:30:20.260] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:30:20.636] INFO: Expecting 41600 events.
[16:30:24.892] INFO: 41600 events read in total (3538ms).
[16:30:25.041] INFO: Test took 4781ms.
[16:30:25.052] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:25.052] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:30:25.052] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:30:25.326] INFO: PixTestAlive::aliveTest() done
[16:30:25.326] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:30:25.326] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 34 23 37 70 65 75 60 74 53 58 60 56 66 40 27 36
[16:30:25.349] INFO: ----------------------------------------------------------------------
[16:30:25.349] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:30:25.349] INFO: ----------------------------------------------------------------------
[16:30:25.351] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:30:25.727] INFO: Expecting 41600 events.
[16:30:28.778] INFO: 41600 events read in total (2334ms).
[16:30:28.778] INFO: Test took 3427ms.
[16:30:28.778] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:28.778] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:30:28.778] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:30:28.779] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:30:29.201] INFO: PixTestAlive::maskTest() done
[16:30:29.201] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:30:29.220] INFO: ----------------------------------------------------------------------
[16:30:29.220] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:30:29.220] INFO: ----------------------------------------------------------------------
[16:30:29.222] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:30:29.596] INFO: Expecting 41600 events.
[16:30:33.848] INFO: 41600 events read in total (3537ms).
[16:30:33.850] INFO: Test took 4628ms.
[16:30:33.861] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:33.861] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:30:33.861] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:30:34.233] INFO: PixTestAlive::addressDecodingTest() done
[16:30:34.234] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:30:34.234] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[16:31:10.035] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:31:10.035] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[16:31:10.035] INFO: ----------------------------------------------------------------------
[16:31:10.035] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:31:10.035] INFO: ----------------------------------------------------------------------
[16:31:10.174] INFO: Expecting 768 events.
[16:31:11.307] INFO: 768 events read in total (417ms).
[16:31:11.307] INFO: Test took 1266ms.
[16:31:11.313] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:31:12.111] INFO: Expecting 41600 events.
[16:31:15.676] INFO: 41600 events read in total (3038ms).
[16:31:15.681] INFO: Test took 4368ms.
[16:31:15.815] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:15.815] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 773555
[16:31:15.815] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:31:15.815] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:31:15.872] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:31:16.538] INFO: Expecting 41600 events.
[16:31:20.197] INFO: 41600 events read in total (3133ms).
[16:31:20.202] INFO: Test took 4330ms.
[16:31:20.330] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:20.330] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 780544
[16:31:20.330] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:31:20.331] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:31:20.390] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:31:21.055] INFO: Expecting 41600 events.
[16:31:24.743] INFO: 41600 events read in total (3162ms).
[16:31:24.748] INFO: Test took 4358ms.
[16:31:24.877] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:24.877] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 784510
[16:31:24.877] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:31:24.878] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:31:24.937] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:31:25.602] INFO: Expecting 41600 events.
[16:31:29.279] INFO: 41600 events read in total (3150ms).
[16:31:29.284] INFO: Test took 4347ms.
[16:31:29.417] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:29.417] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 784858
[16:31:29.417] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:31:29.417] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:31:29.478] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:31:30.144] INFO: Expecting 41600 events.
[16:31:33.835] INFO: 41600 events read in total (3164ms).
[16:31:33.840] INFO: Test took 4362ms.
[16:31:33.971] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:33.971] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783674
[16:31:33.971] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:31:33.971] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:31:34.032] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:31:34.698] INFO: Expecting 41600 events.
[16:31:38.391] INFO: 41600 events read in total (3166ms).
[16:31:38.396] INFO: Test took 4364ms.
[16:31:38.525] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:38.525] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783715
[16:31:38.525] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:31:38.526] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:31:38.588] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:31:39.254] INFO: Expecting 41600 events.
[16:31:42.958] INFO: 41600 events read in total (3177ms).
[16:31:42.962] INFO: Test took 4374ms.
[16:31:43.093] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:43.093] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 784687
[16:31:43.093] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:31:43.093] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:31:43.153] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:31:43.818] INFO: Expecting 41600 events.
[16:31:47.521] INFO: 41600 events read in total (3177ms).
[16:31:47.526] INFO: Test took 4373ms.
[16:31:47.657] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:47.657] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 786582
[16:31:47.657] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:31:47.658] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:31:47.718] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:31:48.385] INFO: Expecting 41600 events.
[16:31:52.090] INFO: 41600 events read in total (3179ms).
[16:31:52.095] INFO: Test took 4377ms.
[16:31:52.226] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:52.226] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 784945
[16:31:52.226] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:31:52.226] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:31:52.286] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:31:52.951] INFO: Expecting 41600 events.
[16:31:56.664] INFO: 41600 events read in total (3186ms).
[16:31:56.669] INFO: Test took 4383ms.
[16:31:56.798] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:56.798] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 784741
[16:31:56.798] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:31:56.799] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:31:56.859] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:31:57.524] INFO: Expecting 41600 events.
[16:32:01.235] INFO: 41600 events read in total (3184ms).
[16:32:01.240] INFO: Test took 4381ms.
[16:32:01.372] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:01.372] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 784793
[16:32:01.372] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:32:01.372] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:32:01.433] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:32:02.100] INFO: Expecting 41600 events.
[16:32:05.795] INFO: 41600 events read in total (3169ms).
[16:32:05.800] INFO: Test took 4367ms.
[16:32:05.929] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:05.929] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783969
[16:32:05.929] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:32:05.929] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:32:05.991] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:32:06.658] INFO: Expecting 41600 events.
[16:32:10.369] INFO: 41600 events read in total (3185ms).
[16:32:10.374] INFO: Test took 4383ms.
[16:32:10.505] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:10.505] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 784531
[16:32:10.505] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:32:10.505] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:32:10.565] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:32:11.230] INFO: Expecting 41600 events.
[16:32:14.941] INFO: 41600 events read in total (3184ms).
[16:32:14.945] INFO: Test took 4380ms.
[16:32:15.077] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:15.077] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783961
[16:32:15.077] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:32:15.078] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:32:15.137] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:32:15.803] INFO: Expecting 41600 events.
[16:32:19.509] INFO: 41600 events read in total (3179ms).
[16:32:19.514] INFO: Test took 4376ms.
[16:32:19.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:19.645] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783449
[16:32:19.645] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:32:19.646] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:32:19.706] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:32:20.371] INFO: Expecting 41600 events.
[16:32:24.089] INFO: 41600 events read in total (3191ms).
[16:32:24.093] INFO: Test took 4387ms.
[16:32:24.224] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:24.224] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 782156
[16:32:24.224] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:32:24.224] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:32:24.286] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:32:24.952] INFO: Expecting 41600 events.
[16:32:28.647] INFO: 41600 events read in total (3168ms).
[16:32:28.652] INFO: Test took 4366ms.
[16:32:28.783] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:28.783] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 782520
[16:32:28.783] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:32:28.784] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:32:28.843] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:32:29.509] INFO: Expecting 41600 events.
[16:32:33.208] INFO: 41600 events read in total (3173ms).
[16:32:33.213] INFO: Test took 4369ms.
[16:32:33.344] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:33.344] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783425
[16:32:33.344] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:32:33.344] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:32:33.404] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:32:34.070] INFO: Expecting 41600 events.
[16:32:37.760] INFO: 41600 events read in total (3164ms).
[16:32:37.765] INFO: Test took 4361ms.
[16:32:37.893] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:37.893] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783757
[16:32:37.893] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:32:37.894] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:32:37.956] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:32:38.620] INFO: Expecting 41600 events.
[16:32:42.141] INFO: 41600 events read in total (2994ms).
[16:32:42.146] INFO: Test took 4190ms.
[16:32:42.272] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:42.272] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 775495
[16:32:42.272] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:32:42.273] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:32:42.556] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 165 eff = 0.999159
[16:32:42.556] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 155 eff = 0.999351
[16:32:42.556] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 161 eff = 0.998678
[16:32:42.557] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 162 eff = 0.997019
[16:32:42.557] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 171 eff = 0.996755
[16:32:42.557] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 162 eff = 0.996659
[16:32:42.557] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 167 eff = 0.99649
[16:32:42.558] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 169 eff = 0.997476
[16:32:42.558] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 151 eff = 0.997428
[16:32:42.558] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 187 eff = 0.996082
[16:32:42.558] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 167 eff = 0.997284
[16:32:42.559] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 161 eff = 0.996827
[16:32:42.559] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 173 eff = 0.997115
[16:32:42.559] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 160 eff = 0.998558
[16:32:42.559] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 154 eff = 0.999159
[16:32:42.560] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 182 eff = 0.999135
[16:33:09.755] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:33:09.755] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[16:33:09.755] INFO: ----------------------------------------------------------------------
[16:33:09.755] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:33:09.755] INFO: ----------------------------------------------------------------------
[16:33:09.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[16:33:09.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[16:33:09.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[16:33:09.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[16:33:09.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[16:33:09.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[16:33:09.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[16:33:09.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[16:33:09.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[16:33:09.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[16:33:09.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[16:33:09.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[16:33:09.771] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:33:10.436] INFO: Expecting 208000 events.
[16:33:25.480] INFO: 208000 events read in total (14517ms).
[16:33:25.499] INFO: Test took 15728ms.
[16:33:26.124] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:26.124] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3618581
[16:33:26.124] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:33:26.125] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:33:26.591] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:33:26.591] INFO: number of red-efficiency pixels: 189 186 357 628 640 653 674 527 499 736 606 614 544 317 154 197
[16:33:26.591] INFO: number of X-ray hits detected: 136688 103439 172100 268956 283777 282649 293809 234136 243321 295975 282251 263168 265238 179703 110079 136732
[16:33:26.591] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:33:26.591] INFO: number of Vcal hits detected: 207807 207802 207600 207297 207274 207219 207248 207408 207447 207107 207273 207315 207404 207644 207839 207798
[16:33:26.591] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.7 99.7 99.7 99.7 99.6 99.7 99.7 99.7 99.8 99.9 99.9
[16:33:26.591] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.6 99.6 99.7 99.7 99.6 99.7 99.7 99.7 99.8 99.9 99.9
[16:33:26.591] INFO: X-ray hit rate [MHz/cm2]: 40.1 30.3 50.4 78.8 83.2 82.8 86.1 68.6 71.3 86.8 82.7 77.1 77.7 52.7 32.3 40.1
[16:33:26.591] INFO: PixTestHighRate::doXPixelAlive() done
[16:33:26.636] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[16:33:26.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[16:33:26.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[16:33:26.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[16:33:26.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[16:33:26.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[16:33:26.637] INFO: PixTest:: pg_setup set to default.
[16:34:05.403] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:34:05.403] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[16:34:07.195] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:34:07.196] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[16:34:07.197] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[16:34:07.197] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[16:34:07.197] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:34:07.197] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:34:07.197] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:34:07.197] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:34:07.197] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:34:07.197] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:34:07.197] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:34:07.197] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:34:07.197] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:34:07.197] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[16:34:07.286] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:34:07.286] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:34:07.295] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:34:07.295] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:34:07.296] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:34:07.296] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:34:07.296] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:34:07.296] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:34:07.298] QUIET: Connection to board 58 closed.
[16:34:07.378] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[16:25:47.255] INFO: *** Welcome to pxar ***
[16:25:47.255] INFO: *** Today: 2016/05/25
[16:25:47.277] INFO: *** Version: v1.9.0-805-geb36
[16:25:47.277] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/dacParameters35_C15.dat
[16:25:47.278] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/tbmParameters_C0b.dat
[16:25:47.278] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/defaultMaskFile.dat
[16:25:47.278] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/trimParameters35_C15.dat
[16:25:47.428] INFO: clk: 4
[16:25:47.428] INFO: ctr: 4
[16:25:47.428] INFO: sda: 19
[16:25:47.428] INFO: tin: 9
[16:25:47.428] INFO: level: 15
[16:25:47.428] INFO: triggerdelay: 0
[16:25:47.428] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:25:47.428] INFO: Log level: DEBUG
[16:25:47.440] QUIET: Connection to board DTB_WRPRHI opened.
[16:25:47.443] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:25:47.446] INFO: RPC call hashes of host and DTB match: 398089610
[16:25:49.046] INFO: DUT info:
[16:25:49.046] INFO: The DUT currently contains the following objects:
[16:25:49.046] INFO: 2 TBM Cores tbm08c (2 ON)
[16:25:49.046] INFO: TBM Core alpha (0): 7 registers set
[16:25:49.046] INFO: TBM Core beta (1): 7 registers set
[16:25:49.046] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:25:49.046] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.046] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:25:49.046] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:25:49.046] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:25:49.046] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:25:49.046] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:25:49.046] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:25:49.046] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:25:49.046] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:25:49.046] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:25:49.046] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:25:49.046] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:25:49.046] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:25:49.046] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:25:49.046] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:25:49.047] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:25:49.048] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:25:49.049] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:25:49.051] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32780288
[16:25:49.051] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x170ff10
[16:25:49.051] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1689e30
[16:25:49.051] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f8aa1d94010
[16:25:49.051] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f8aa7fff510
[16:25:49.051] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32788480 fPxarMemory = 0x7f8aa1d94010
[16:25:49.052] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 380.2mA
[16:25:49.053] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 464.7mA
[16:25:49.053] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -1.6 C
[16:25:49.542] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1cd2a10
[16:25:49.605] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:25:49.605] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:25:49.605] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:25:49.611] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:25:49.611] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:25:49.611] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:25:49.631] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:25:49.631] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:25:49.641] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:25:49.641] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:25:49.657] INFO: PixTestCmd::init()
[16:25:49.661] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:25:49.662] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:25:49.662] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mo109/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/phCalibrationFitErr35_C15.dat
[16:25:49.909] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:25:49.909] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:25:49.923] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:25:49.929] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:25:49.929] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[16:25:49.929] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:25:49.949] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:25:49.949] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:25:49.949] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:25:49.949] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:25:49.949] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:25:49.955] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:25:49.955] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:25:49.970] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:25:49.970] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:25:49.970] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:25:49.970] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mo109/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mo109/readbackCal_C15.dat
[16:25:49.980] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:25:49.988] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:25:49.988] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:25:49.988] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:25:49.988] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:25:50.000] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:25:50.001] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:26:31.838] INFO: ######################################################################
[16:26:31.838] INFO: PixTestAlive::doTest()
[16:26:31.838] INFO: ######################################################################
[16:26:31.842] INFO: ----------------------------------------------------------------------
[16:26:31.842] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:26:31.842] INFO: ----------------------------------------------------------------------
[16:26:31.844] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:32.219] INFO: Expecting 41600 events.
[16:26:36.497] INFO: 41600 events read in total (3559ms).
[16:26:36.645] INFO: Test took 4801ms.
[16:26:36.655] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:36.655] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:26:36.655] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:26:36.929] INFO: PixTestAlive::aliveTest() done
[16:26:36.929] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:26:36.929] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 38 28 52 82 84 114 73 70 54 79 101 75 87 55 41 37
[16:26:36.951] INFO: ----------------------------------------------------------------------
[16:26:36.951] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:26:36.951] INFO: ----------------------------------------------------------------------
[16:26:36.954] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:37.328] INFO: Expecting 41600 events.
[16:26:40.379] INFO: 41600 events read in total (2335ms).
[16:26:40.380] INFO: Test took 3426ms.
[16:26:40.380] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:40.380] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:26:40.380] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:26:40.380] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:26:40.802] INFO: PixTestAlive::maskTest() done
[16:26:40.802] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:26:40.823] INFO: ----------------------------------------------------------------------
[16:26:40.823] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:26:40.823] INFO: ----------------------------------------------------------------------
[16:26:40.825] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:41.199] INFO: Expecting 41600 events.
[16:26:45.447] INFO: 41600 events read in total (3533ms).
[16:26:45.449] INFO: Test took 4624ms.
[16:26:45.461] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:45.461] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:26:45.461] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:26:45.829] INFO: PixTestAlive::addressDecodingTest() done
[16:26:45.829] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:26:45.829] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[16:27:40.875] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:27:40.875] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[16:27:40.875] INFO: ----------------------------------------------------------------------
[16:27:40.875] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:27:40.875] INFO: ----------------------------------------------------------------------
[16:27:41.014] INFO: Expecting 768 events.
[16:27:42.147] INFO: 768 events read in total (417ms).
[16:27:42.147] INFO: Test took 1266ms.
[16:27:42.153] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:27:42.951] INFO: Expecting 41600 events.
[16:27:46.699] INFO: 41600 events read in total (3221ms).
[16:27:46.705] INFO: Test took 4552ms.
[16:27:46.863] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:46.863] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 936302
[16:27:46.863] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:27:46.863] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:27:46.931] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:27:47.597] INFO: Expecting 41600 events.
[16:27:51.423] INFO: 41600 events read in total (3300ms).
[16:27:51.428] INFO: Test took 4497ms.
[16:27:51.579] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:51.580] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 939897
[16:27:51.580] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:27:51.580] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:27:51.649] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:27:52.314] INFO: Expecting 41600 events.
[16:27:56.172] INFO: 41600 events read in total (3331ms).
[16:27:56.177] INFO: Test took 4528ms.
[16:27:56.336] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:56.336] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 943174
[16:27:56.336] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:27:56.337] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:27:56.405] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:27:57.073] INFO: Expecting 41600 events.
[16:28:00.931] INFO: 41600 events read in total (3331ms).
[16:28:00.936] INFO: Test took 4531ms.
[16:28:01.088] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:01.088] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 943205
[16:28:01.088] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:28:01.089] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:01.158] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:01.824] INFO: Expecting 41600 events.
[16:28:05.689] INFO: 41600 events read in total (3339ms).
[16:28:05.694] INFO: Test took 4536ms.
[16:28:05.846] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:05.847] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 944427
[16:28:05.847] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:28:05.847] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:05.917] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:06.582] INFO: Expecting 41600 events.
[16:28:10.456] INFO: 41600 events read in total (3347ms).
[16:28:10.461] INFO: Test took 4545ms.
[16:28:10.614] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:10.614] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 942062
[16:28:10.614] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:28:10.615] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:10.685] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:11.350] INFO: Expecting 41600 events.
[16:28:15.246] INFO: 41600 events read in total (3369ms).
[16:28:15.252] INFO: Test took 4567ms.
[16:28:15.408] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:15.408] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 941316
[16:28:15.408] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:28:15.408] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:15.477] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:16.142] INFO: Expecting 41600 events.
[16:28:20.007] INFO: 41600 events read in total (3338ms).
[16:28:20.013] INFO: Test took 4536ms.
[16:28:20.167] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:20.167] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 942316
[16:28:20.167] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:28:20.168] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:20.236] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:20.901] INFO: Expecting 41600 events.
[16:28:24.778] INFO: 41600 events read in total (3350ms).
[16:28:24.783] INFO: Test took 4547ms.
[16:28:24.940] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:24.940] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 943421
[16:28:24.941] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:28:24.941] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:25.010] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:25.676] INFO: Expecting 41600 events.
[16:28:29.558] INFO: 41600 events read in total (3355ms).
[16:28:29.563] INFO: Test took 4553ms.
[16:28:29.719] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:29.719] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 945211
[16:28:29.719] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:28:29.720] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:29.789] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:30.456] INFO: Expecting 41600 events.
[16:28:34.343] INFO: 41600 events read in total (3361ms).
[16:28:34.349] INFO: Test took 4560ms.
[16:28:34.505] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:34.505] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 942873
[16:28:34.505] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:28:34.505] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:34.573] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:35.239] INFO: Expecting 41600 events.
[16:28:39.117] INFO: 41600 events read in total (3351ms).
[16:28:39.122] INFO: Test took 4549ms.
[16:28:39.279] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:39.279] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 944973
[16:28:39.279] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:28:39.280] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:39.349] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:40.014] INFO: Expecting 41600 events.
[16:28:43.908] INFO: 41600 events read in total (3368ms).
[16:28:43.913] INFO: Test took 4564ms.
[16:28:44.067] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:44.067] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 942950
[16:28:44.067] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:28:44.068] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:44.140] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:44.805] INFO: Expecting 41600 events.
[16:28:48.687] INFO: 41600 events read in total (3355ms).
[16:28:48.692] INFO: Test took 4552ms.
[16:28:48.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:48.850] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 943480
[16:28:48.850] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:28:48.851] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:48.921] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:49.587] INFO: Expecting 41600 events.
[16:28:53.471] INFO: 41600 events read in total (3357ms).
[16:28:53.476] INFO: Test took 4555ms.
[16:28:53.630] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:53.630] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 942285
[16:28:53.630] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:28:53.630] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:53.702] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:54.369] INFO: Expecting 41600 events.
[16:28:58.255] INFO: 41600 events read in total (3359ms).
[16:28:58.261] INFO: Test took 4559ms.
[16:28:58.413] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:58.413] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 941754
[16:28:58.413] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:28:58.414] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:28:58.486] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:28:59.153] INFO: Expecting 41600 events.
[16:29:03.047] INFO: 41600 events read in total (3367ms).
[16:29:03.053] INFO: Test took 4567ms.
[16:29:03.209] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:03.210] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 944694
[16:29:03.210] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:29:03.210] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:29:03.278] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:29:03.944] INFO: Expecting 41600 events.
[16:29:07.826] INFO: 41600 events read in total (3356ms).
[16:29:07.831] INFO: Test took 4553ms.
[16:29:07.984] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:07.984] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 942174
[16:29:07.984] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:29:07.985] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:29:08.056] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:29:08.722] INFO: Expecting 41600 events.
[16:29:12.602] INFO: 41600 events read in total (3353ms).
[16:29:12.607] INFO: Test took 4551ms.
[16:29:12.764] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:12.764] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 942414
[16:29:12.764] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:29:12.765] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:29:12.835] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:29:13.501] INFO: Expecting 41600 events.
[16:29:17.232] INFO: 41600 events read in total (3205ms).
[16:29:17.237] INFO: Test took 4402ms.
[16:29:17.388] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:17.388] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 934856
[16:29:17.388] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:29:17.389] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:29:17.682] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 161 eff = 0.998774
[16:29:17.682] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 170 eff = 0.999135
[16:29:17.682] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 161 eff = 0.997452
[16:29:17.683] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 168 eff = 0.995337
[16:29:17.683] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 172 eff = 0.994471
[16:29:17.683] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 158 eff = 0.994351
[16:29:17.683] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 170 eff = 0.994351
[16:29:17.684] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 167 eff = 0.995313
[16:29:17.684] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 148 eff = 0.994784
[16:29:17.684] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 184 eff = 0.994303
[16:29:17.684] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 161 eff = 0.994279
[16:29:17.685] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 159 eff = 0.994639
[16:29:17.685] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 170 eff = 0.995216
[16:29:17.685] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 161 eff = 0.997332
[16:29:17.685] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 154 eff = 0.998918
[16:29:17.686] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 169 eff = 0.998558
[16:29:24.299] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:29:24.299] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[16:29:24.299] INFO: ----------------------------------------------------------------------
[16:29:24.299] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:29:24.299] INFO: ----------------------------------------------------------------------
[16:29:24.299] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[16:29:24.300] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[16:29:24.300] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[16:29:24.300] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[16:29:24.300] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[16:29:24.300] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[16:29:24.300] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[16:29:24.300] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[16:29:24.300] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[16:29:24.300] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[16:29:24.300] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[16:29:24.300] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[16:29:24.315] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:29:24.980] INFO: Expecting 208000 events.
[16:29:41.410] INFO: 208000 events read in total (15903ms).
[16:29:41.434] INFO: Test took 17119ms.
[16:29:42.216] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:42.216] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4393982
[16:29:42.216] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:29:42.216] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:29:42.732] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:29:42.732] INFO: number of red-efficiency pixels: 267 199 479 843 908 963 1023 845 812 1065 1063 865 845 470 217 329
[16:29:42.732] INFO: number of X-ray hits detected: 167132 125558 209934 326875 344193 343986 358582 285432 297013 360133 343236 320028 323458 220572 134181 167109
[16:29:42.732] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:29:42.732] INFO: number of Vcal hits detected: 207724 207788 207436 207039 206927 206823 206768 206989 207031 206717 206674 206943 207002 207466 207767 207647
[16:29:42.732] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.6 99.5 99.5 99.5 99.5 99.6 99.4 99.4 99.5 99.6 99.8 99.9 99.8
[16:29:42.732] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.5 99.5 99.4 99.4 99.5 99.5 99.4 99.4 99.5 99.5 99.7 99.9 99.8
[16:29:42.732] INFO: X-ray hit rate [MHz/cm2]: 49.0 36.8 61.5 95.8 100.9 100.8 105.1 83.7 87.1 105.6 100.6 93.8 94.8 64.7 39.3 49.0
[16:29:42.732] INFO: PixTestHighRate::doXPixelAlive() done
[16:29:42.779] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[16:29:42.779] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[16:29:42.779] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[16:29:42.779] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[16:29:42.779] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[16:29:42.779] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[16:29:42.780] INFO: PixTest:: pg_setup set to default.
[16:29:56.099] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:29:56.099] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[16:29:57.819] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:29:57.820] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[16:29:57.820] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[16:29:57.820] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:29:57.820] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:29:57.821] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:29:57.821] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:29:57.821] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:29:57.821] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:29:57.821] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:29:57.821] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:29:57.821] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:29:57.821] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:29:57.821] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[16:29:57.913] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:29:57.913] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:29:57.922] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:29:57.922] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:29:57.922] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:29:57.922] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:29:57.922] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:29:57.922] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:29:57.925] QUIET: Connection to board 58 closed.
[16:29:58.005] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master