Test Date: 1973-11-29 16:33
Analysis date: 2016-06-09 11:01
Logfile
ph02ma_mi131_052516.log
[17:13:40.714] INFO: *** Welcome to pxar ***
[17:13:40.714] INFO: *** Today: 2016/05/25
[17:13:40.736] INFO: *** Version: v1.9.0-805-geb36
[17:13:40.736] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/dacParameters35_C15.dat
[17:13:40.737] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/tbmParameters_C0b.dat
[17:13:40.738] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/defaultMaskFile.dat
[17:13:40.738] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/trimParameters35_C15.dat
[17:13:40.886] INFO: clk: 4
[17:13:40.886] INFO: ctr: 4
[17:13:40.886] INFO: sda: 19
[17:13:40.886] INFO: tin: 9
[17:13:40.886] INFO: level: 15
[17:13:40.886] INFO: triggerdelay: 0
[17:13:40.886] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:13:40.886] INFO: Log level: DEBUG
[17:13:40.897] QUIET: Connection to board DTB_WRPRHI opened.
[17:13:40.900] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[17:13:40.903] INFO: RPC call hashes of host and DTB match: 398089610
[17:13:42.502] INFO: DUT info:
[17:13:42.502] INFO: The DUT currently contains the following objects:
[17:13:42.502] INFO: 2 TBM Cores tbm08c (2 ON)
[17:13:42.502] INFO: TBM Core alpha (0): 7 registers set
[17:13:42.502] INFO: TBM Core beta (1): 7 registers set
[17:13:42.502] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:13:42.502] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.502] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.503] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.503] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.503] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.503] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.503] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.503] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.503] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.503] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.503] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.503] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.503] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.503] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.503] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.503] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:13:42.503] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:13:42.504] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:13:42.505] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:13:42.508] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32763904
[17:13:42.508] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x15f2f10
[17:13:42.508] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x156ce30
[17:13:42.508] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7ff0c9d94010
[17:13:42.508] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7ff0cffff510
[17:13:42.508] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32772096 fPxarMemory = 0x7ff0c9d94010
[17:13:42.509] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 378.6mA
[17:13:42.510] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[17:13:42.510] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[17:13:42.998] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1bb59b0
[17:13:43.059] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:13:43.060] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:13:43.060] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:13:43.066] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:13:43.066] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:13:43.066] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:13:43.077] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:13:43.077] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:13:43.099] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:13:43.100] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:13:43.111] INFO: PixTestCmd::init()
[17:13:43.131] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:13:43.131] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:13:43.131] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mi131/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/phCalibrationFitErr35_C15.dat
[17:13:43.377] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:13:43.377] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:13:43.392] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:13:43.398] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:13:43.398] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:13:43.398] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:13:43.418] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:13:43.418] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:13:43.418] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:13:43.418] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:13:43.418] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:13:43.424] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:13:43.425] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:13:43.440] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:13:43.440] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:13:43.440] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:13:43.440] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/readbackCal_C15.dat
[17:13:43.450] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:13:43.458] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:13:43.458] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:13:43.458] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:13:43.458] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:13:43.471] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:13:43.471] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:13:47.135] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[17:13:51.615] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 55
[17:13:53.548] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[17:13:53.548] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[17:13:53.548] INFO: ----------------------------------------------------------------------
[17:13:53.548] INFO: PixTestXray::doPhRun() fParRunSeconds = 55
[17:13:53.548] INFO: ----------------------------------------------------------------------
[17:13:54.520] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 55 seconds, fEventsMax = 5500000
[17:14:06.407] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[17:14:06.411] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:14:30.867] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1171211 events.
[17:14:37.955] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1171211, pixels seen in all events: 6197868
[17:14:38.138] INFO: Resuming triggers.
[17:14:50.026] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[17:14:50.058] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:15:14.851] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1171148 events.
[17:15:21.883] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1171148, pixels seen in all events: 6197664
[17:15:22.053] INFO: Resuming triggers.
ph06ma_mi131_052516.log
[16:53:14.588] INFO: *** Welcome to pxar ***
[16:53:14.588] INFO: *** Today: 2016/05/25
[16:53:14.612] INFO: *** Version: v1.9.0-805-geb36
[16:53:14.612] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/dacParameters35_C15.dat
[16:53:14.686] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/tbmParameters_C0b.dat
[16:53:14.692] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/defaultMaskFile.dat
[16:53:14.693] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/trimParameters35_C15.dat
[16:53:14.906] INFO: clk: 4
[16:53:14.906] INFO: ctr: 4
[16:53:14.906] INFO: sda: 19
[16:53:14.906] INFO: tin: 9
[16:53:14.906] INFO: level: 15
[16:53:14.906] INFO: triggerdelay: 0
[16:53:14.906] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:53:14.906] INFO: Log level: DEBUG
[16:53:14.918] QUIET: Connection to board DTB_WRPRHI opened.
[16:53:14.921] 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:53:14.924] INFO: RPC call hashes of host and DTB match: 398089610
[16:53:16.523] INFO: DUT info:
[16:53:16.523] INFO: The DUT currently contains the following objects:
[16:53:16.523] INFO: 2 TBM Cores tbm08c (2 ON)
[16:53:16.523] INFO: TBM Core alpha (0): 7 registers set
[16:53:16.523] INFO: TBM Core beta (1): 7 registers set
[16:53:16.523] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:53:16.523] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.523] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.523] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.523] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.523] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.523] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.523] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.523] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.523] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.523] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.523] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.523] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.523] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.523] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.523] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.523] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:53:16.524] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:53:16.525] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:53:16.526] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:53:16.528] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33677312
[16:53:16.528] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1339f10
[16:53:16.528] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x12b3e30
[16:53:16.528] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fe825d94010
[16:53:16.529] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fe82bfff510
[16:53:16.529] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33685504 fPxarMemory = 0x7fe825d94010
[16:53:16.530] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 377.8mA
[16:53:16.531] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[16:53:16.531] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -1.3 C
[16:53:17.019] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x18fca50
[16:53:17.080] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:53:17.080] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:53:17.080] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:53:17.086] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:53:17.086] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:53:17.086] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:53:17.094] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:53:17.094] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:53:17.115] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:53:17.115] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:53:17.124] INFO: PixTestCmd::init()
[16:53:17.129] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:53:17.129] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:53:17.129] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mi131/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/phCalibrationFitErr35_C15.dat
[16:53:17.480] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:53:17.480] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:53:17.495] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:53:17.501] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:53:17.501] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[16:53:17.501] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:53:17.522] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:53:17.522] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:53:17.522] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:53:17.522] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:53:17.522] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:53:17.529] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:53:17.529] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:53:17.553] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:53:17.553] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:53:17.553] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:53:17.553] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/readbackCal_C15.dat
[16:53:17.583] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:53:17.591] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:53:17.591] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:53:17.591] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:53:17.591] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:53:17.605] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:53:17.605] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:53:34.979] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:53:34.980] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 380.2mA
[16:53:34.981] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[16:53:34.981] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:53:34.981] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:53:34.981] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:53:34.981] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:53:34.981] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:53:34.981] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:53:34.981] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:53:34.981] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:53:34.981] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:53:34.982] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:53:34.982] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[16:53:34.982] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:53:34.982] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:53:34.982] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:53:34.982] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:53:34.982] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:53:34.982] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:53:34.982] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:53:34.982] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:53:35.020] QUIET: Connection to board 58 closed.
[16:53:35.100] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[17:09:00.317] INFO: *** Welcome to pxar ***
[17:09:00.317] INFO: *** Today: 2016/05/25
[17:09:00.340] INFO: *** Version: v1.9.0-805-geb36
[17:09:00.340] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/dacParameters35_C15.dat
[17:09:00.341] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/tbmParameters_C0b.dat
[17:09:00.341] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/defaultMaskFile.dat
[17:09:00.341] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/trimParameters35_C15.dat
[17:09:00.490] INFO: clk: 4
[17:09:00.490] INFO: ctr: 4
[17:09:00.490] INFO: sda: 19
[17:09:00.490] INFO: tin: 9
[17:09:00.490] INFO: level: 15
[17:09:00.490] INFO: triggerdelay: 0
[17:09:00.490] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:09:00.490] INFO: Log level: DEBUG
[17:09:00.500] QUIET: Connection to board DTB_WRPRHI opened.
[17:09:00.504] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[17:09:00.507] INFO: RPC call hashes of host and DTB match: 398089610
[17:09:02.106] INFO: DUT info:
[17:09:02.106] INFO: The DUT currently contains the following objects:
[17:09:02.107] INFO: 2 TBM Cores tbm08c (2 ON)
[17:09:02.107] INFO: TBM Core alpha (0): 7 registers set
[17:09:02.107] INFO: TBM Core beta (1): 7 registers set
[17:09:02.107] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:09:02.107] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:09:02.107] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:09:02.107] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:09:02.107] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:09:02.107] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:09:02.107] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:09:02.107] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:09:02.107] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:09:02.107] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:09:02.107] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:09:02.107] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:09:02.107] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:09:02.107] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:09:02.107] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:09:02.107] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:09:02.108] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:09:02.109] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:09:02.110] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:09:02.110] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:09:02.110] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:09:02.110] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:09:02.110] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:09:02.110] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:09:02.110] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:09:02.110] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:09:02.110] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:09:02.110] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:09:02.110] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:09:02.110] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:09:02.110] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:09:02.110] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:09:02.110] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:09:02.112] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32763904
[17:09:02.112] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x24e2f10
[17:09:02.112] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x245ce30
[17:09:02.112] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f492dd94010
[17:09:02.112] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f4933fff510
[17:09:02.112] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32772096 fPxarMemory = 0x7f492dd94010
[17:09:02.113] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[17:09:02.115] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[17:09:02.115] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[17:09:02.602] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2aa59a0
[17:09:02.664] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:09:02.665] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:09:02.665] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:09:02.671] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:09:02.671] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:09:02.671] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:09:02.683] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:09:02.683] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:09:02.702] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:09:02.702] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:09:02.710] INFO: PixTestCmd::init()
[17:09:02.717] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:09:02.717] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:09:02.717] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mi131/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/phCalibrationFitErr35_C15.dat
[17:09:02.962] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:09:02.963] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:09:02.978] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:09:02.983] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:09:02.984] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:09:02.984] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:09:03.002] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:09:03.002] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:09:03.002] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:09:03.002] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:09:03.002] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:09:03.009] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:09:03.009] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:09:03.023] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:09:03.023] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:09:03.023] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:09:03.023] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/readbackCal_C15.dat
[17:09:03.034] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:09:03.042] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:09:03.042] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:09:03.042] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:09:03.042] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:09:03.054] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:09:03.054] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:09:06.959] INFO: ######################################################################
[17:09:06.959] INFO: PixTestAlive::doTest()
[17:09:06.959] INFO: ######################################################################
[17:09:06.962] INFO: ----------------------------------------------------------------------
[17:09:06.962] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:09:06.962] INFO: ----------------------------------------------------------------------
[17:09:06.965] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:07.341] INFO: Expecting 41600 events.
[17:09:11.604] INFO: 41600 events read in total (3545ms).
[17:09:11.750] INFO: Test took 4785ms.
[17:09:11.761] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:11.761] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[17:09:11.761] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[17:09:12.034] INFO: PixTestAlive::aliveTest() done
[17:09:12.034] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:09:12.034] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 19 17 36 46 50 41 41 40 36 43 47 33 43 42 21 25
[17:09:12.057] INFO: ----------------------------------------------------------------------
[17:09:12.057] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:09:12.057] INFO: ----------------------------------------------------------------------
[17:09:12.060] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:12.434] INFO: Expecting 41600 events.
[17:09:15.491] INFO: 41600 events read in total (2342ms).
[17:09:15.491] INFO: Test took 3431ms.
[17:09:15.491] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:15.491] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[17:09:15.491] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[17:09:15.492] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:09:15.914] INFO: PixTestAlive::maskTest() done
[17:09:15.914] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:09:15.933] INFO: ----------------------------------------------------------------------
[17:09:15.933] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:09:15.933] INFO: ----------------------------------------------------------------------
[17:09:15.935] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:16.310] INFO: Expecting 41600 events.
[17:09:20.568] INFO: 41600 events read in total (3542ms).
[17:09:20.569] INFO: Test took 4634ms.
[17:09:20.580] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:20.580] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[17:09:20.580] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[17:09:20.951] INFO: PixTestAlive::addressDecodingTest() done
[17:09:20.951] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:09:20.951] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[17:09:22.548] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[17:09:22.548] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[17:09:22.548] INFO: ----------------------------------------------------------------------
[17:09:22.548] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:09:22.548] INFO: ----------------------------------------------------------------------
[17:09:22.687] INFO: Expecting 768 events.
[17:09:23.822] INFO: 768 events read in total (420ms).
[17:09:23.822] INFO: Test took 1269ms.
[17:09:23.828] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:24.626] INFO: Expecting 41600 events.
[17:09:27.901] INFO: 41600 events read in total (2749ms).
[17:09:27.906] INFO: Test took 4078ms.
[17:09:28.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:28.002] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 529321
[17:09:28.002] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[17:09:28.003] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:28.045] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:28.711] INFO: Expecting 41600 events.
[17:09:32.096] INFO: 41600 events read in total (2858ms).
[17:09:32.102] INFO: Test took 4057ms.
[17:09:32.196] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:32.196] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 536028
[17:09:32.196] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[17:09:32.196] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:32.239] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:32.905] INFO: Expecting 41600 events.
[17:09:36.329] INFO: 41600 events read in total (2898ms).
[17:09:36.335] INFO: Test took 4096ms.
[17:09:36.429] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:36.429] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 538935
[17:09:36.429] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[17:09:36.429] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:36.473] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:37.138] INFO: Expecting 41600 events.
[17:09:40.545] INFO: 41600 events read in total (2880ms).
[17:09:40.551] INFO: Test took 4078ms.
[17:09:40.644] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:40.644] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537528
[17:09:40.644] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[17:09:40.644] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:40.688] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:41.353] INFO: Expecting 41600 events.
[17:09:44.760] INFO: 41600 events read in total (2880ms).
[17:09:44.766] INFO: Test took 4078ms.
[17:09:44.860] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:44.860] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537670
[17:09:44.860] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[17:09:44.861] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:44.904] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:45.569] INFO: Expecting 41600 events.
[17:09:48.986] INFO: 41600 events read in total (2890ms).
[17:09:48.990] INFO: Test took 4086ms.
[17:09:49.082] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:49.082] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 539035
[17:09:49.082] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[17:09:49.082] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:49.126] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:49.791] INFO: Expecting 41600 events.
[17:09:53.221] INFO: 41600 events read in total (2903ms).
[17:09:53.225] INFO: Test took 4099ms.
[17:09:53.320] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:53.320] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 539073
[17:09:53.320] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[17:09:53.320] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:53.363] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:54.028] INFO: Expecting 41600 events.
[17:09:57.467] INFO: 41600 events read in total (2912ms).
[17:09:57.471] INFO: Test took 4108ms.
[17:09:57.565] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:57.565] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537520
[17:09:57.565] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[17:09:57.565] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:57.611] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:58.278] INFO: Expecting 41600 events.
[17:10:01.713] INFO: 41600 events read in total (2909ms).
[17:10:01.718] INFO: Test took 4107ms.
[17:10:01.812] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:01.812] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537859
[17:10:01.812] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[17:10:01.813] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:10:01.858] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:10:02.525] INFO: Expecting 41600 events.
[17:10:05.967] INFO: 41600 events read in total (2915ms).
[17:10:05.971] INFO: Test took 4113ms.
[17:10:06.063] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:06.063] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537465
[17:10:06.063] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[17:10:06.063] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:10:06.107] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:10:06.772] INFO: Expecting 41600 events.
[17:10:10.205] INFO: 41600 events read in total (2906ms).
[17:10:10.209] INFO: Test took 4102ms.
[17:10:10.303] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:10.303] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537368
[17:10:10.303] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[17:10:10.303] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:10:10.347] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:10:11.012] INFO: Expecting 41600 events.
[17:10:14.448] INFO: 41600 events read in total (2909ms).
[17:10:14.452] INFO: Test took 4105ms.
[17:10:14.543] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:14.543] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537933
[17:10:14.543] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[17:10:14.543] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:10:14.588] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:10:15.254] INFO: Expecting 41600 events.
[17:10:18.690] INFO: 41600 events read in total (2910ms).
[17:10:18.694] INFO: Test took 4106ms.
[17:10:18.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:18.786] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537896
[17:10:18.786] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[17:10:18.786] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:10:18.830] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:10:19.495] INFO: Expecting 41600 events.
[17:10:22.927] INFO: 41600 events read in total (2905ms).
[17:10:22.931] INFO: Test took 4101ms.
[17:10:23.023] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:23.023] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537212
[17:10:23.023] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[17:10:23.024] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:10:23.067] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:10:23.732] INFO: Expecting 41600 events.
[17:10:27.173] INFO: 41600 events read in total (2914ms).
[17:10:27.177] INFO: Test took 4110ms.
[17:10:27.270] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:27.270] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 538389
[17:10:27.270] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[17:10:27.270] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:10:27.314] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:10:27.980] INFO: Expecting 41600 events.
[17:10:31.407] INFO: 41600 events read in total (2901ms).
[17:10:31.411] INFO: Test took 4097ms.
[17:10:31.504] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:31.504] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537184
[17:10:31.504] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[17:10:31.505] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:10:31.550] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:10:32.216] INFO: Expecting 41600 events.
[17:10:35.648] INFO: 41600 events read in total (2905ms).
[17:10:35.652] INFO: Test took 4102ms.
[17:10:35.744] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:35.744] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 536573
[17:10:35.744] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[17:10:35.745] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:10:35.788] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:10:36.453] INFO: Expecting 41600 events.
[17:10:39.881] INFO: 41600 events read in total (2901ms).
[17:10:39.885] INFO: Test took 4097ms.
[17:10:39.980] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:39.980] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 536702
[17:10:39.980] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[17:10:39.980] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:10:40.025] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:10:40.691] INFO: Expecting 41600 events.
[17:10:44.096] INFO: 41600 events read in total (2878ms).
[17:10:44.102] INFO: Test took 4077ms.
[17:10:44.195] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:44.195] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537480
[17:10:44.195] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[17:10:44.195] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:10:44.240] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:10:44.905] INFO: Expecting 41600 events.
[17:10:48.162] INFO: 41600 events read in total (2730ms).
[17:10:48.166] INFO: Test took 3926ms.
[17:10:48.255] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:48.255] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 528312
[17:10:48.255] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[17:10:48.255] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:10:48.520] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 134 eff = 0.999615
[17:10:48.521] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 147 eff = 0.999688
[17:10:48.521] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 158 eff = 0.999111
[17:10:48.521] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 167 eff = 0.999207
[17:10:48.521] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 164 eff = 0.998822
[17:10:48.521] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 175 eff = 0.998365
[17:10:48.521] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 148 eff = 0.998678
[17:10:48.521] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 171 eff = 0.998726
[17:10:48.522] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 166 eff = 0.998894
[17:10:48.522] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 135 eff = 0.998389
[17:10:48.522] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 185 eff = 0.998558
[17:10:48.522] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 175 eff = 0.998822
[17:10:48.522] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 181 eff = 0.998582
[17:10:48.522] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 159 eff = 0.999135
[17:10:48.523] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 149 eff = 0.999687
[17:10:48.523] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 146 eff = 0.999519
[17:10:54.060] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[17:10:54.060] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[17:10:54.060] INFO: ----------------------------------------------------------------------
[17:10:54.060] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:10:54.060] INFO: ----------------------------------------------------------------------
[17:10:54.060] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[17:10:54.060] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[17:10:54.060] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[17:10:54.060] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[17:10:54.060] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[17:10:54.060] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[17:10:54.060] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[17:10:54.060] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[17:10:54.060] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[17:10:54.060] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[17:10:54.060] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[17:10:54.060] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[17:10:54.076] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:10:54.744] INFO: Expecting 208000 events.
[17:11:08.016] INFO: 208000 events read in total (12745ms).
[17:11:08.030] INFO: Test took 13954ms.
[17:11:08.456] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:11:08.456] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2405690
[17:11:08.456] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[17:11:08.456] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:11:08.848] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:11:08.849] INFO: number of red-efficiency pixels: 140 106 199 244 290 307 283 250 325 379 312 267 369 177 86 106
[17:11:08.849] INFO: number of X-ray hits detected: 84116 67040 118286 172705 187159 184333 185863 152491 162800 194680 192437 176960 179645 121315 71432 87868
[17:11:08.849] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:11:08.849] INFO: number of Vcal hits detected: 207856 207893 207793 207750 207686 207681 207710 207739 207658 207593 207668 207719 207608 207816 207912 207891
[17:11:08.849] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.8 99.9 100.0 99.9
[17:11:08.849] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.8 99.8 99.8 99.9 99.8 99.9 100.0 99.9
[17:11:08.849] INFO: X-ray hit rate [MHz/cm2]: 24.7 19.6 34.7 50.6 54.9 54.0 54.5 44.7 47.7 57.1 56.4 51.9 52.7 35.6 20.9 25.8
[17:11:08.849] INFO: PixTestHighRate::doXPixelAlive() done
[17:11:08.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[17:11:08.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[17:11:08.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[17:11:08.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[17:11:08.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[17:11:08.894] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[17:11:08.894] INFO: PixTest:: pg_setup set to default.
[17:11:14.772] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[17:11:14.772] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[17:11:16.212] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:11:16.213] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 381mA
[17:11:16.213] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.1mA
[17:11:16.214] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[17:11:16.214] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:11:16.214] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:11:16.214] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:11:16.214] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:11:16.214] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:11:16.214] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:11:16.214] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:11:16.214] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:11:16.214] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:11:16.214] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:11:16.302] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:11:16.302] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:11:16.311] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:11:16.311] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:11:16.311] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:11:16.311] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:11:16.311] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:11:16.311] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:11:16.313] QUIET: Connection to board 58 closed.
[17:11:16.393] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[17:06:23.752] INFO: *** Welcome to pxar ***
[17:06:23.752] INFO: *** Today: 2016/05/25
[17:06:23.775] INFO: *** Version: v1.9.0-805-geb36
[17:06:23.775] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/dacParameters35_C15.dat
[17:06:23.776] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/tbmParameters_C0b.dat
[17:06:23.776] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/defaultMaskFile.dat
[17:06:23.776] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/trimParameters35_C15.dat
[17:06:23.924] INFO: clk: 4
[17:06:23.924] INFO: ctr: 4
[17:06:23.924] INFO: sda: 19
[17:06:23.924] INFO: tin: 9
[17:06:23.924] INFO: level: 15
[17:06:23.924] INFO: triggerdelay: 0
[17:06:23.925] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:06:23.925] INFO: Log level: DEBUG
[17:06:23.936] QUIET: Connection to board DTB_WRPRHI opened.
[17:06:23.939] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[17:06:23.942] INFO: RPC call hashes of host and DTB match: 398089610
[17:06:25.542] INFO: DUT info:
[17:06:25.542] INFO: The DUT currently contains the following objects:
[17:06:25.542] INFO: 2 TBM Cores tbm08c (2 ON)
[17:06:25.542] INFO: TBM Core alpha (0): 7 registers set
[17:06:25.542] INFO: TBM Core beta (1): 7 registers set
[17:06:25.542] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:06:25.542] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.542] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.542] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.542] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.542] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.542] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.542] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.542] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.542] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.542] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.542] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.542] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.542] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.542] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.542] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.542] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:06:25.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:06:25.544] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:06:25.545] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:06:25.547] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35360768
[17:06:25.547] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x290ff10
[17:06:25.547] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2889e30
[17:06:25.547] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f92e9393010
[17:06:25.547] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f92ef5fe510
[17:06:25.547] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35368960 fPxarMemory = 0x7f92e9393010
[17:06:25.548] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 378.6mA
[17:06:25.550] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[17:06:25.550] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[17:06:26.039] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2ed29b0
[17:06:26.100] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:06:26.100] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:06:26.101] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:06:26.106] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:06:26.106] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:06:26.106] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:06:26.118] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:06:26.118] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:06:26.137] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:06:26.137] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:06:26.149] INFO: PixTestCmd::init()
[17:06:26.167] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:06:26.167] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:06:26.167] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mi131/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/phCalibrationFitErr35_C15.dat
[17:06:26.412] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:06:26.412] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:06:26.427] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:06:26.433] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:06:26.433] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:06:26.433] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:06:26.453] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:06:26.453] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:06:26.453] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:06:26.453] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:06:26.453] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:06:26.460] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:06:26.460] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:06:26.475] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:06:26.475] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:06:26.475] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:06:26.475] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/readbackCal_C15.dat
[17:06:26.485] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:06:26.493] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:06:26.493] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:06:26.493] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:06:26.493] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:06:26.512] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:06:26.512] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:06:29.775] INFO: ######################################################################
[17:06:29.775] INFO: PixTestAlive::doTest()
[17:06:29.775] INFO: ######################################################################
[17:06:29.778] INFO: ----------------------------------------------------------------------
[17:06:29.778] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:06:29.778] INFO: ----------------------------------------------------------------------
[17:06:29.781] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:06:30.162] INFO: Expecting 41600 events.
[17:06:34.415] INFO: 41600 events read in total (3534ms).
[17:06:34.562] INFO: Test took 4781ms.
[17:06:34.572] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:06:34.572] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[17:06:34.572] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[17:06:34.846] INFO: PixTestAlive::aliveTest() done
[17:06:34.846] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:06:34.846] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 31 18 51 71 78 68 76 46 59 90 62 58 53 49 27 27
[17:06:34.868] INFO: ----------------------------------------------------------------------
[17:06:34.868] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:06:34.868] INFO: ----------------------------------------------------------------------
[17:06:34.871] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:06:35.245] INFO: Expecting 41600 events.
[17:06:38.296] INFO: 41600 events read in total (2336ms).
[17:06:38.296] INFO: Test took 3425ms.
[17:06:38.296] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:06:38.296] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[17:06:38.296] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[17:06:38.297] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:06:38.720] INFO: PixTestAlive::maskTest() done
[17:06:38.720] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:06:38.738] INFO: ----------------------------------------------------------------------
[17:06:38.738] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:06:38.738] INFO: ----------------------------------------------------------------------
[17:06:38.740] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:06:39.117] INFO: Expecting 41600 events.
[17:06:43.366] INFO: 41600 events read in total (3534ms).
[17:06:43.367] INFO: Test took 4627ms.
[17:06:43.378] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:06:43.378] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[17:06:43.378] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[17:06:43.750] INFO: PixTestAlive::addressDecodingTest() done
[17:06:43.750] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:06:43.750] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[17:06:45.548] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[17:06:45.548] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[17:06:45.548] INFO: ----------------------------------------------------------------------
[17:06:45.548] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:06:45.548] INFO: ----------------------------------------------------------------------
[17:06:45.687] INFO: Expecting 768 events.
[17:06:46.819] INFO: 768 events read in total (417ms).
[17:06:46.819] INFO: Test took 1265ms.
[17:06:46.825] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:06:47.623] INFO: Expecting 41600 events.
[17:06:51.169] INFO: 41600 events read in total (3019ms).
[17:06:51.174] INFO: Test took 4349ms.
[17:06:51.308] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:06:51.308] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 777231
[17:06:51.308] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[17:06:51.308] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:06:51.366] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:06:52.031] INFO: Expecting 41600 events.
[17:06:55.692] INFO: 41600 events read in total (3135ms).
[17:06:55.697] INFO: Test took 4331ms.
[17:06:55.828] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:06:55.828] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 785909
[17:06:55.828] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[17:06:55.829] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:06:55.889] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:06:56.555] INFO: Expecting 41600 events.
[17:07:00.225] INFO: 41600 events read in total (3143ms).
[17:07:00.230] INFO: Test took 4341ms.
[17:07:00.362] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:00.362] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 787788
[17:07:00.362] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[17:07:00.362] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:07:00.422] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:01.087] INFO: Expecting 41600 events.
[17:07:04.762] INFO: 41600 events read in total (3149ms).
[17:07:04.767] INFO: Test took 4345ms.
[17:07:04.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:04.898] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 785993
[17:07:04.898] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[17:07:04.898] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:07:04.958] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:05.623] INFO: Expecting 41600 events.
[17:07:09.311] INFO: 41600 events read in total (3162ms).
[17:07:09.317] INFO: Test took 4359ms.
[17:07:09.446] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:09.446] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 788925
[17:07:09.446] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[17:07:09.447] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:07:09.507] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:10.172] INFO: Expecting 41600 events.
[17:07:13.866] INFO: 41600 events read in total (3167ms).
[17:07:13.870] INFO: Test took 4363ms.
[17:07:14.001] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:14.001] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 788078
[17:07:14.001] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[17:07:14.002] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:07:14.065] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:14.732] INFO: Expecting 41600 events.
[17:07:18.435] INFO: 41600 events read in total (3177ms).
[17:07:18.440] INFO: Test took 4375ms.
[17:07:18.572] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:18.572] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 789870
[17:07:18.572] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[17:07:18.572] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:07:18.633] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:19.298] INFO: Expecting 41600 events.
[17:07:23.007] INFO: 41600 events read in total (3182ms).
[17:07:23.012] INFO: Test took 4379ms.
[17:07:23.142] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:23.142] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 786504
[17:07:23.142] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[17:07:23.143] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:07:23.202] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:23.868] INFO: Expecting 41600 events.
[17:07:27.575] INFO: 41600 events read in total (3181ms).
[17:07:27.580] INFO: Test took 4378ms.
[17:07:27.712] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:27.712] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 787255
[17:07:27.712] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[17:07:27.712] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:07:27.773] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:28.438] INFO: Expecting 41600 events.
[17:07:32.154] INFO: 41600 events read in total (3189ms).
[17:07:32.158] INFO: Test took 4386ms.
[17:07:32.291] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:32.291] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 789265
[17:07:32.291] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[17:07:32.292] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:07:32.352] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:33.017] INFO: Expecting 41600 events.
[17:07:36.710] INFO: 41600 events read in total (3167ms).
[17:07:36.715] INFO: Test took 4363ms.
[17:07:36.847] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:36.847] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 787165
[17:07:36.847] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[17:07:36.847] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:07:36.908] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:37.573] INFO: Expecting 41600 events.
[17:07:41.274] INFO: 41600 events read in total (3175ms).
[17:07:41.279] INFO: Test took 4371ms.
[17:07:41.409] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:41.409] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 788574
[17:07:41.409] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[17:07:41.410] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:07:41.469] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:42.134] INFO: Expecting 41600 events.
[17:07:45.843] INFO: 41600 events read in total (3182ms).
[17:07:45.848] INFO: Test took 4379ms.
[17:07:45.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:45.979] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 789958
[17:07:45.979] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[17:07:45.980] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:07:46.040] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:46.705] INFO: Expecting 41600 events.
[17:07:50.413] INFO: 41600 events read in total (3181ms).
[17:07:50.418] INFO: Test took 4378ms.
[17:07:50.550] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:50.550] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 786920
[17:07:50.550] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[17:07:50.551] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:07:50.611] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:51.276] INFO: Expecting 41600 events.
[17:07:54.975] INFO: 41600 events read in total (3172ms).
[17:07:54.980] INFO: Test took 4369ms.
[17:07:55.111] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:55.111] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 786856
[17:07:55.111] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[17:07:55.111] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:07:55.171] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:55.836] INFO: Expecting 41600 events.
[17:07:59.532] INFO: 41600 events read in total (3170ms).
[17:07:59.537] INFO: Test took 4366ms.
[17:07:59.666] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:59.666] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 788199
[17:07:59.667] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[17:07:59.667] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:07:59.728] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:08:00.393] INFO: Expecting 41600 events.
[17:08:04.107] INFO: 41600 events read in total (3187ms).
[17:08:04.112] INFO: Test took 4384ms.
[17:08:04.245] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:04.245] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 788030
[17:08:04.245] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[17:08:04.246] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:08:04.307] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:08:04.973] INFO: Expecting 41600 events.
[17:08:08.671] INFO: 41600 events read in total (3171ms).
[17:08:08.676] INFO: Test took 4369ms.
[17:08:08.806] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:08.806] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 787632
[17:08:08.806] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[17:08:08.807] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:08:08.869] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:08:09.536] INFO: Expecting 41600 events.
[17:08:13.225] INFO: 41600 events read in total (3162ms).
[17:08:13.230] INFO: Test took 4361ms.
[17:08:13.359] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:13.359] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 788596
[17:08:13.359] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[17:08:13.359] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:08:13.421] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:08:14.086] INFO: Expecting 41600 events.
[17:08:17.631] INFO: 41600 events read in total (3018ms).
[17:08:17.636] INFO: Test took 4215ms.
[17:08:17.763] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:17.763] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 780318
[17:08:17.763] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[17:08:17.764] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:08:18.046] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 162 eff = 0.999327
[17:08:18.046] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 166 eff = 0.999495
[17:08:18.046] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 161 eff = 0.998365
[17:08:18.047] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 165 eff = 0.997428
[17:08:18.047] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 165 eff = 0.996442
[17:08:18.047] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 171 eff = 0.996779
[17:08:18.047] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 153 eff = 0.996923
[17:08:18.047] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 179 eff = 0.997596
[17:08:18.047] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 175 eff = 0.997139
[17:08:18.048] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 141 eff = 0.995481
[17:08:18.048] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 185 eff = 0.996202
[17:08:18.048] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 175 eff = 0.996875
[17:08:18.048] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 183 eff = 0.996587
[17:08:18.048] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 161 eff = 0.998221
[17:08:18.048] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 158 eff = 0.999279
[17:08:18.049] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 149 eff = 0.999014
[17:08:23.100] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[17:08:23.100] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[17:08:23.100] INFO: ----------------------------------------------------------------------
[17:08:23.100] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:08:23.100] INFO: ----------------------------------------------------------------------
[17:08:23.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[17:08:23.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[17:08:23.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[17:08:23.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[17:08:23.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[17:08:23.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[17:08:23.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[17:08:23.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[17:08:23.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[17:08:23.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[17:08:23.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[17:08:23.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[17:08:23.117] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:08:23.782] INFO: Expecting 208000 events.
[17:08:38.910] INFO: 208000 events read in total (14601ms).
[17:08:38.929] INFO: Test took 15812ms.
[17:08:39.554] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:39.554] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3634725
[17:08:39.554] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[17:08:39.554] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:08:40.024] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:08:40.024] INFO: number of red-efficiency pixels: 162 159 315 520 655 645 665 504 489 743 708 648 694 350 149 197
[17:08:40.024] INFO: number of X-ray hits detected: 128634 102443 180471 263653 285411 282096 283427 232884 247009 295899 294734 270492 274190 184116 109111 133595
[17:08:40.024] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:08:40.024] INFO: number of Vcal hits detected: 207836 207837 207651 207440 207267 207278 207228 207434 207466 207152 207200 207274 207216 207614 207843 207794
[17:08:40.024] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.7 99.7 99.8 99.6 99.6 99.7 99.7 99.8 99.9 99.9
[17:08:40.024] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.7 99.6 99.7 99.7 99.6 99.6 99.7 99.6 99.8 99.9 99.9
[17:08:40.024] INFO: X-ray hit rate [MHz/cm2]: 37.7 30.0 52.9 77.3 83.7 82.7 83.1 68.3 72.4 86.7 86.4 79.3 80.4 54.0 32.0 39.2
[17:08:40.024] INFO: PixTestHighRate::doXPixelAlive() done
[17:08:40.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[17:08:40.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[17:08:40.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[17:08:40.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[17:08:40.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[17:08:40.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[17:08:40.070] INFO: PixTest:: pg_setup set to default.
[17:08:50.412] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[17:08:50.412] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[17:08:51.828] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:08:51.828] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 381mA
[17:08:51.829] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.1mA
[17:08:51.829] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[17:08:51.829] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:08:51.829] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:08:51.829] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:08:51.830] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:08:51.830] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:08:51.830] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:08:51.830] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:08:51.830] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:08:51.830] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:08:51.830] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:08:51.920] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:08:51.920] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:08:51.929] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:08:51.929] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:08:51.929] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:08:51.929] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:08:51.929] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:08:51.929] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:08:51.931] QUIET: Connection to board 58 closed.
[17:08:52.011] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[17:03:45.144] INFO: *** Welcome to pxar ***
[17:03:45.144] INFO: *** Today: 2016/05/25
[17:03:45.166] INFO: *** Version: v1.9.0-805-geb36
[17:03:45.166] INFO: readRocDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/dacParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/dacParameters35_C15.dat
[17:03:45.167] INFO: readTbmDacs: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/tbmParameters_C0a.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/tbmParameters_C0b.dat
[17:03:45.168] INFO: readMaskFile: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/defaultMaskFile.dat
[17:03:45.168] INFO: readTrimFile: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/trimParameters35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/trimParameters35_C15.dat
[17:03:45.318] INFO: clk: 4
[17:03:45.318] INFO: ctr: 4
[17:03:45.318] INFO: sda: 19
[17:03:45.318] INFO: tin: 9
[17:03:45.318] INFO: level: 15
[17:03:45.318] INFO: triggerdelay: 0
[17:03:45.318] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:03:45.318] INFO: Log level: DEBUG
[17:03:45.330] QUIET: Connection to board DTB_WRPRHI opened.
[17:03:45.333] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[17:03:45.336] INFO: RPC call hashes of host and DTB match: 398089610
[17:03:46.936] INFO: DUT info:
[17:03:46.936] INFO: The DUT currently contains the following objects:
[17:03:46.936] INFO: 2 TBM Cores tbm08c (2 ON)
[17:03:46.936] INFO: TBM Core alpha (0): 7 registers set
[17:03:46.936] INFO: TBM Core beta (1): 7 registers set
[17:03:46.936] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:03:46.936] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:46.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:03:46.936] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:03:46.936] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:03:46.936] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:03:46.937] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:03:46.938] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:03:46.939] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:03:46.941] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34861056
[17:03:46.941] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xdfef10
[17:03:46.941] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xd78e30
[17:03:46.941] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f8d41d94010
[17:03:46.941] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f8d47fff510
[17:03:46.941] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34869248 fPxarMemory = 0x7f8d41d94010
[17:03:46.942] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 378.6mA
[17:03:46.943] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[17:03:46.944] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[17:03:47.432] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x13c18f0
[17:03:47.492] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:03:47.492] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:03:47.493] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:03:47.507] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:03:47.508] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:03:47.508] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:03:47.517] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:03:47.517] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:03:47.535] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:03:47.535] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:03:47.544] INFO: PixTestCmd::init()
[17:03:47.568] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:03:47.568] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:03:47.568] INFO: readGainPedestalParameters /home/CMS_pixel/Testing/pxar_fermi/data/mi131/phCalibrationFitErr35_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/phCalibrationFitErr35_C15.dat
[17:03:47.817] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:03:47.817] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:03:47.834] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:03:47.858] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:03:47.858] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:03:47.858] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:03:47.888] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:03:47.888] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:03:47.888] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:03:47.888] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:03:47.888] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:03:47.895] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:03:47.895] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:03:47.909] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:03:47.909] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:03:47.909] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:03:47.909] INFO: readReadbackCal: /home/CMS_pixel/Testing/pxar_fermi/data/mi131/readbackCal_C0.dat .. /home/CMS_pixel/Testing/pxar_fermi/data/mi131/readbackCal_C15.dat
[17:03:47.921] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:03:47.929] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:03:47.929] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:03:47.929] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:03:47.929] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:03:47.941] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:03:47.941] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:03:53.703] INFO: ######################################################################
[17:03:53.703] INFO: PixTestAlive::doTest()
[17:03:53.703] INFO: ######################################################################
[17:03:53.706] INFO: ----------------------------------------------------------------------
[17:03:53.706] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:03:53.706] INFO: ----------------------------------------------------------------------
[17:03:53.709] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:03:54.085] INFO: Expecting 41600 events.
[17:03:58.346] INFO: 41600 events read in total (3543ms).
[17:03:58.494] INFO: Test took 4785ms.
[17:03:58.503] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:03:58.503] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[17:03:58.504] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[17:03:58.778] INFO: PixTestAlive::aliveTest() done
[17:03:58.778] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:03:58.778] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 31 32 44 73 82 67 80 67 67 86 80 84 74 59 39 34
[17:03:58.800] INFO: ----------------------------------------------------------------------
[17:03:58.800] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:03:58.800] INFO: ----------------------------------------------------------------------
[17:03:58.802] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:03:59.176] INFO: Expecting 41600 events.
[17:04:02.227] INFO: 41600 events read in total (2336ms).
[17:04:02.227] INFO: Test took 3425ms.
[17:04:02.227] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:02.227] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[17:04:02.227] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[17:04:02.228] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:04:02.651] INFO: PixTestAlive::maskTest() done
[17:04:02.651] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:04:02.669] INFO: ----------------------------------------------------------------------
[17:04:02.669] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:04:02.669] INFO: ----------------------------------------------------------------------
[17:04:02.671] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:03.049] INFO: Expecting 41600 events.
[17:04:07.286] INFO: 41600 events read in total (3522ms).
[17:04:07.287] INFO: Test took 4615ms.
[17:04:07.298] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:07.298] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[17:04:07.298] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[17:04:07.669] INFO: PixTestAlive::addressDecodingTest() done
[17:04:07.669] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:04:07.669] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[17:04:10.980] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[17:04:10.980] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[17:04:10.980] INFO: ----------------------------------------------------------------------
[17:04:10.980] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:04:10.980] INFO: ----------------------------------------------------------------------
[17:04:11.119] INFO: Expecting 768 events.
[17:04:12.251] INFO: 768 events read in total (417ms).
[17:04:12.251] INFO: Test took 1266ms.
[17:04:12.257] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:13.055] INFO: Expecting 41600 events.
[17:04:16.815] INFO: 41600 events read in total (3233ms).
[17:04:16.821] INFO: Test took 4564ms.
[17:04:16.983] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:16.983] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 934210
[17:04:16.983] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[17:04:16.983] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:17.050] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:17.715] INFO: Expecting 41600 events.
[17:04:21.569] INFO: 41600 events read in total (3327ms).
[17:04:21.575] INFO: Test took 4525ms.
[17:04:21.728] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:21.728] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 944592
[17:04:21.728] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[17:04:21.729] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:21.801] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:22.466] INFO: Expecting 41600 events.
[17:04:26.334] INFO: 41600 events read in total (3341ms).
[17:04:26.340] INFO: Test took 4539ms.
[17:04:26.494] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:26.494] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 944159
[17:04:26.494] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[17:04:26.495] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:26.567] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:27.232] INFO: Expecting 41600 events.
[17:04:31.106] INFO: 41600 events read in total (3347ms).
[17:04:31.112] INFO: Test took 4545ms.
[17:04:31.267] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:31.267] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 944883
[17:04:31.267] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[17:04:31.267] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:31.339] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:32.004] INFO: Expecting 41600 events.
[17:04:35.871] INFO: 41600 events read in total (3341ms).
[17:04:35.877] INFO: Test took 4538ms.
[17:04:36.033] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:36.033] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 944671
[17:04:36.033] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[17:04:36.033] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:36.102] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:36.766] INFO: Expecting 41600 events.
[17:04:40.641] INFO: 41600 events read in total (3348ms).
[17:04:40.647] INFO: Test took 4545ms.
[17:04:40.800] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:40.800] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 943586
[17:04:40.800] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[17:04:40.801] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:40.872] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:41.539] INFO: Expecting 41600 events.
[17:04:45.414] INFO: 41600 events read in total (3349ms).
[17:04:45.419] INFO: Test took 4547ms.
[17:04:45.573] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:45.573] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 945367
[17:04:45.573] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[17:04:45.574] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:45.647] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:46.314] INFO: Expecting 41600 events.
[17:04:50.196] INFO: 41600 events read in total (3356ms).
[17:04:50.201] INFO: Test took 4554ms.
[17:04:50.358] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:50.358] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 945555
[17:04:50.358] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[17:04:50.359] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:50.427] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:51.093] INFO: Expecting 41600 events.
[17:04:54.969] INFO: 41600 events read in total (3350ms).
[17:04:54.975] INFO: Test took 4548ms.
[17:04:55.134] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:55.134] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 945018
[17:04:55.134] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[17:04:55.134] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:55.204] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:04:55.876] INFO: Expecting 41600 events.
[17:04:59.760] INFO: 41600 events read in total (3358ms).
[17:04:59.765] INFO: Test took 4561ms.
[17:04:59.922] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:04:59.922] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 946048
[17:04:59.922] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[17:04:59.922] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:04:59.991] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:05:00.657] INFO: Expecting 41600 events.
[17:05:04.535] INFO: 41600 events read in total (3352ms).
[17:05:04.541] INFO: Test took 4549ms.
[17:05:04.700] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:04.700] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 944733
[17:05:04.700] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[17:05:04.700] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:05:04.770] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:05:05.437] INFO: Expecting 41600 events.
[17:05:09.320] INFO: 41600 events read in total (3357ms).
[17:05:09.326] INFO: Test took 4556ms.
[17:05:09.483] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:09.483] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 945831
[17:05:09.483] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[17:05:09.483] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:05:09.553] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:05:10.220] INFO: Expecting 41600 events.
[17:05:14.093] INFO: 41600 events read in total (3347ms).
[17:05:14.099] INFO: Test took 4546ms.
[17:05:14.252] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:14.252] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 943981
[17:05:14.252] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[17:05:14.253] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:05:14.325] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:05:14.991] INFO: Expecting 41600 events.
[17:05:18.878] INFO: 41600 events read in total (3360ms).
[17:05:18.884] INFO: Test took 4559ms.
[17:05:19.043] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:19.043] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 945430
[17:05:19.043] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[17:05:19.043] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:05:19.112] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:05:19.777] INFO: Expecting 41600 events.
[17:05:23.652] INFO: 41600 events read in total (3348ms).
[17:05:23.657] INFO: Test took 4545ms.
[17:05:23.812] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:23.812] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 945945
[17:05:23.812] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[17:05:23.812] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:05:23.882] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:05:24.548] INFO: Expecting 41600 events.
[17:05:28.433] INFO: 41600 events read in total (3359ms).
[17:05:28.439] INFO: Test took 4557ms.
[17:05:28.592] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:28.592] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 945021
[17:05:28.592] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[17:05:28.593] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:05:28.664] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:05:29.331] INFO: Expecting 41600 events.
[17:05:33.221] INFO: 41600 events read in total (3364ms).
[17:05:33.227] INFO: Test took 4562ms.
[17:05:33.385] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:33.385] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 944763
[17:05:33.385] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[17:05:33.385] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:05:33.454] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:05:34.120] INFO: Expecting 41600 events.
[17:05:38.010] INFO: 41600 events read in total (3365ms).
[17:05:38.016] INFO: Test took 4562ms.
[17:05:38.172] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:38.172] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 945077
[17:05:38.172] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[17:05:38.172] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:05:38.242] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:05:38.907] INFO: Expecting 41600 events.
[17:05:42.771] INFO: 41600 events read in total (3338ms).
[17:05:42.777] INFO: Test took 4535ms.
[17:05:42.932] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:42.932] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 944305
[17:05:42.932] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[17:05:42.933] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:05:43.001] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:05:43.666] INFO: Expecting 41600 events.
[17:05:47.382] INFO: 41600 events read in total (3189ms).
[17:05:47.387] INFO: Test took 4386ms.
[17:05:47.539] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:05:47.539] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 936896
[17:05:47.539] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[17:05:47.540] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:05:47.831] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 147 eff = 0.99887
[17:05:47.832] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 169 eff = 0.99899
[17:05:47.832] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 164 eff = 0.997236
[17:05:47.832] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 169 eff = 0.995697
[17:05:47.832] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 164 eff = 0.994423
[17:05:47.832] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 174 eff = 0.994255
[17:05:47.832] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 151 eff = 0.994063
[17:05:47.832] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 177 eff = 0.995745
[17:05:47.833] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 175 eff = 0.995216
[17:05:47.833] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 143 eff = 0.993942
[17:05:47.833] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 183 eff = 0.994423
[17:05:47.833] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 175 eff = 0.99512
[17:05:47.833] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 190 eff = 0.994615
[17:05:47.833] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 164 eff = 0.996899
[17:05:47.834] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 149 eff = 0.99875
[17:05:47.834] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 158 eff = 0.99863
[17:05:54.676] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[17:05:54.676] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[17:05:54.676] INFO: ----------------------------------------------------------------------
[17:05:54.676] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:05:54.676] INFO: ----------------------------------------------------------------------
[17:05:54.676] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[17:05:54.676] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[17:05:54.676] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[17:05:54.676] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[17:05:54.676] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[17:05:54.676] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[17:05:54.676] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[17:05:54.676] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[17:05:54.676] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[17:05:54.676] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[17:05:54.676] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[17:05:54.676] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[17:05:54.692] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:05:55.357] INFO: Expecting 208000 events.
[17:06:11.873] INFO: 208000 events read in total (15989ms).
[17:06:11.896] INFO: Test took 17204ms.
[17:06:12.672] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:06:12.672] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4401856
[17:06:12.672] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[17:06:12.672] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:06:13.190] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:06:13.190] INFO: number of red-efficiency pixels: 303 205 521 790 957 962 943 712 765 1066 1040 892 901 546 249 226
[17:06:13.190] INFO: number of X-ray hits detected: 156514 125028 218750 321362 345866 342942 342500 284178 302018 359110 357107 327446 333249 223828 132967 162431
[17:06:13.190] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:06:13.190] INFO: number of Vcal hits detected: 207678 207780 207399 207118 206832 206833 206874 207164 207105 206700 206747 206925 206940 207324 207733 207763
[17:06:13.190] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.6 99.5 99.5 99.5 99.6 99.6 99.4 99.4 99.5 99.5 99.7 99.9 99.9
[17:06:13.190] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.4 99.4 99.5 99.6 99.6 99.4 99.4 99.5 99.5 99.7 99.9 99.9
[17:06:13.190] INFO: X-ray hit rate [MHz/cm2]: 45.9 36.6 64.1 94.2 101.4 100.5 100.4 83.3 88.5 105.3 104.7 96.0 97.7 65.6 39.0 47.6
[17:06:13.190] INFO: PixTestHighRate::doXPixelAlive() done
[17:06:13.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[17:06:13.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[17:06:13.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[17:06:13.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[17:06:13.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[17:06:13.238] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[17:06:13.238] INFO: PixTest:: pg_setup set to default.
[17:06:15.259] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[17:06:15.259] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[17:06:16.492] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:06:16.492] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 381mA
[17:06:16.493] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[17:06:16.493] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[17:06:16.493] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:06:16.493] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:06:16.493] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:06:16.493] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:06:16.493] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:06:16.493] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:06:16.494] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:06:16.494] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:06:16.494] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:06:16.494] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:06:16.585] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:06:16.585] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:06:16.594] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:06:16.594] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:06:16.594] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:06:16.594] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:06:16.594] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:06:16.594] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:06:16.598] QUIET: Connection to board 58 closed.
[17:06:16.678] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master