Test Date: 1973-11-29 16:33
Analysis date: 2016-06-29 17:22
Logfile
hrData_40.log
[15:05:57.581] INFO: *** Welcome to pxar ***
[15:05:57.581] INFO: *** Today: 2016/06/28
[15:05:57.610] INFO: *** Version: v1.9.0-814-g7497
[15:05:57.610] INFO: readRocDacs: data/mp443/dacParameters35_C0.dat .. data/mp443/dacParameters35_C15.dat
[15:05:57.611] INFO: readTbmDacs: data/mp443/tbmParameters_C0a.dat .. data/mp443/tbmParameters_C0b.dat
[15:05:57.611] INFO: readMaskFile: data/mp443/defaultMaskFile.dat
[15:05:57.611] INFO: readTrimFile: data/mp443/trimParameters35_C0.dat .. data/mp443/trimParameters35_C15.dat
[15:05:57.764] INFO: clk: 4
[15:05:57.764] INFO: ctr: 4
[15:05:57.764] INFO: sda: 19
[15:05:57.764] INFO: tin: 9
[15:05:57.764] INFO: level: 15
[15:05:57.764] INFO: triggerdelay: 0
[15:05:57.764] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:05:57.764] INFO: Log level: DEBUG
[15:05:57.775] QUIET: Connection to board DTB_WRMNMR opened.
[15:05:57.778] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[15:05:57.781] INFO: RPC call hashes of host and DTB match: 398089610
[15:05:59.384] INFO: DUT info:
[15:05:59.384] INFO: The DUT currently contains the following objects:
[15:05:59.384] INFO: 2 TBM Cores tbm08c (2 ON)
[15:05:59.384] INFO: TBM Core alpha (0): 7 registers set
[15:05:59.384] INFO: TBM Core beta (1): 7 registers set
[15:05:59.384] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:05:59.384] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.384] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.385] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.385] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.385] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.385] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.385] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.385] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.385] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.385] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.385] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.385] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.385] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.385] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.385] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.385] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:59.385] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:05:59.385] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:05:59.385] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:05:59.385] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:05:59.385] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:05:59.385] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:05:59.385] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:05:59.385] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:05:59.385] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:05:59.385] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:05:59.385] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:05:59.385] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:05:59.386] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:05:59.387] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:05:59.388] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:05:59.390] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31539200
[15:05:59.390] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xee40d0
[15:05:59.390] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xc86360
[15:05:59.390] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fa599d94010
[15:05:59.391] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fa59ffff510
[15:05:59.391] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31547392 fPxarMemory = 0x7fa599d94010
[15:05:59.392] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 392.3mA
[15:05:59.393] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 471.9mA
[15:05:59.393] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:05:59.888] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x12cee10
[15:05:59.947] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:05:59.947] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:05:59.947] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:05:59.952] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:05:59.953] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:05:59.953] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:05:59.962] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:05:59.962] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:05:59.973] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:05:59.973] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:05:59.986] INFO: PixTestCmd::init()
[15:05:59.994] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:05:59.994] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:05:59.994] INFO: readGainPedestalParameters data/mp443/phCalibrationFitErr35_C0.dat .. data/mp443/phCalibrationFitErr35_C15.dat
[15:06:00.250] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:06:00.251] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:06:00.263] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:06:00.269] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:06:00.270] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:06:00.270] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:06:00.294] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:06:00.294] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:06:00.294] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:06:00.294] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:06:00.294] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:06:00.300] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:06:00.300] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:06:00.316] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:06:00.316] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:06:00.316] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:06:00.316] INFO: readReadbackCal: data/mp443/readbackCal_C0.dat .. data/mp443/readbackCal_C15.dat
[15:06:00.326] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:06:00.333] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:06:00.333] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:06:00.333] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:06:00.333] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:06:00.348] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:06:00.349] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:06:08.083] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[15:06:10.115] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[15:06:11.552] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[15:06:11.552] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[15:06:11.552] INFO: ----------------------------------------------------------------------
[15:06:11.552] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[15:06:11.552] INFO: ----------------------------------------------------------------------
[15:06:12.526] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[15:06:24.273] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:06:24.277] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:06:51.703] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1157407 events.
[15:06:58.663] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1157407, pixels seen in all events: 6363064
[15:06:58.844] INFO: Resuming triggers.
[15:07:10.591] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:07:10.627] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:07:37.889] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1157305 events.
[15:07:44.859] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1157305, pixels seen in all events: 6363821
[15:07:45.117] INFO: Resuming triggers.
[15:07:56.865] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[15:07:56.903] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:08:24.067] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1157379 events.
[15:08:31.042] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1157379, pixels seen in all events: 6362382
[15:08:31.304] INFO: Resuming triggers.
[15:08:43.054] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:08:43.089] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:09:10.298] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1157515 events.
[15:09:17.331] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1157515, pixels seen in all events: 6360976
[15:09:17.419] INFO: Resuming triggers.
[15:09:29.166] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[15:09:29.204] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:09:56.338] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1157336 events.
[15:10:03.395] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1157336, pixels seen in all events: 6363107
[15:10:03.494] INFO: Resuming triggers.
[15:10:04.837] INFO: data taking finished, elapsed time: 60 seconds.
[15:10:05.033] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:10:08.313] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 132246 events.
[15:10:09.109] DEBUG: <PixTestXray.cc/processData:L823> # events read: 132246, pixels seen in all events: 726097
[15:10:09.163] INFO: PixTest:: pg_setup set to default.
[15:10:09.201] INFO: PixTestXray::doPhRun() done
[15:10:15.352] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:10:15.353] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 393.9mA
[15:10:15.353] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 472.7mA
[15:10:15.353] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:10:15.353] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:10:15.354] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:10:15.354] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:10:15.354] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:10:15.354] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:10:15.354] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:10:15.354] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:10:15.354] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:10:15.354] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:10:15.354] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:10:15.354] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:10:15.354] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:10:15.354] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:10:15.354] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:10:15.354] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:10:15.355] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:10:15.355] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:10:15.355] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:10:15.632] QUIET: Connection to board 43 closed.
[15:10:15.711] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[15:10:20.089] INFO: *** Welcome to pxar ***
[15:10:20.089] INFO: *** Today: 2016/06/28
[15:10:20.115] INFO: *** Version: v1.9.0-814-g7497
[15:10:20.115] INFO: readRocDacs: data/mp443/dacParameters35_C0.dat .. data/mp443/dacParameters35_C15.dat
[15:10:20.116] INFO: readTbmDacs: data/mp443/tbmParameters_C0a.dat .. data/mp443/tbmParameters_C0b.dat
[15:10:20.116] INFO: readMaskFile: data/mp443/defaultMaskFile.dat
[15:10:20.116] INFO: readTrimFile: data/mp443/trimParameters35_C0.dat .. data/mp443/trimParameters35_C15.dat
[15:10:20.266] INFO: clk: 4
[15:10:20.266] INFO: ctr: 4
[15:10:20.266] INFO: sda: 19
[15:10:20.266] INFO: tin: 9
[15:10:20.266] INFO: level: 15
[15:10:20.266] INFO: triggerdelay: 0
[15:10:20.266] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:10:20.266] INFO: Log level: DEBUG
[15:10:20.277] QUIET: Connection to board DTB_WRMNMR opened.
[15:10:20.280] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[15:10:20.283] INFO: RPC call hashes of host and DTB match: 398089610
[15:10:21.885] INFO: DUT info:
[15:10:21.886] INFO: The DUT currently contains the following objects:
[15:10:21.886] INFO: 2 TBM Cores tbm08c (2 ON)
[15:10:21.886] INFO: TBM Core alpha (0): 7 registers set
[15:10:21.886] INFO: TBM Core beta (1): 7 registers set
[15:10:21.886] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:10:21.886] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:10:21.886] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:10:21.887] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:10:21.888] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:10:21.889] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:10:21.890] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:10:21.890] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:10:21.892] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31334400
[15:10:21.892] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x22ce0d0
[15:10:21.892] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2070360
[15:10:21.892] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f903fd83010
[15:10:21.892] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f9045fee510
[15:10:21.892] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31342592 fPxarMemory = 0x7f903fd83010
[15:10:21.893] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 391.5mA
[15:10:21.894] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 471.9mA
[15:10:21.894] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[15:10:22.389] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x26b8d60
[15:10:22.452] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:10:22.452] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:10:22.452] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:10:22.458] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:10:22.458] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:10:22.458] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:10:22.467] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:10:22.467] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:10:22.488] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:10:22.488] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:10:22.497] INFO: PixTestCmd::init()
[15:10:22.506] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:10:22.506] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:10:22.506] INFO: readGainPedestalParameters data/mp443/phCalibrationFitErr35_C0.dat .. data/mp443/phCalibrationFitErr35_C15.dat
[15:10:22.749] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:10:22.750] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:10:22.766] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:10:22.771] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:10:22.771] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:10:22.771] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:10:22.795] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:10:22.795] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:10:22.795] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:10:22.795] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:10:22.795] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:10:22.800] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:10:22.800] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:10:22.818] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:10:22.818] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:10:22.818] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:10:22.818] INFO: readReadbackCal: data/mp443/readbackCal_C0.dat .. data/mp443/readbackCal_C15.dat
[15:10:22.827] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:10:22.834] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:10:22.834] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:10:22.834] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:10:22.834] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:10:22.851] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:10:22.851] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:10:26.627] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[15:10:28.467] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[15:10:35.808] INFO: ######################################################################
[15:10:35.808] INFO: PixTestXray::doTest()
[15:10:35.808] INFO: ######################################################################
[15:10:35.808] INFO: ----------------------------------------------------------------------
[15:10:35.808] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[15:10:35.808] INFO: ----------------------------------------------------------------------
[15:10:36.782] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[15:10:44.172] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:10:44.176] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:11:09.416] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 728189 events.
[15:11:21.814] DEBUG: <PixTestXray.cc/processData:L823> # events read: 728189, pixels seen in all events: 11515196
[15:11:22.045] INFO: Resuming triggers.
[15:11:29.434] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[15:11:29.471] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:11:54.555] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 727877 events.
[15:12:06.955] DEBUG: <PixTestXray.cc/processData:L823> # events read: 727877, pixels seen in all events: 11517018
[15:12:07.167] INFO: Resuming triggers.
[15:12:14.558] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[15:12:14.595] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:12:39.675] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 728151 events.
[15:12:52.054] DEBUG: <PixTestXray.cc/processData:L823> # events read: 728151, pixels seen in all events: 11514316
[15:12:52.265] INFO: Resuming triggers.
[15:12:59.652] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[15:12:59.692] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:13:24.890] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 727646 events.
[15:13:37.347] DEBUG: <PixTestXray.cc/processData:L823> # events read: 727646, pixels seen in all events: 11520408
[15:13:37.559] INFO: Resuming triggers.
[15:13:44.950] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[15:13:44.985] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:14:10.060] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 728084 events.
[15:14:22.478] DEBUG: <PixTestXray.cc/processData:L823> # events read: 728084, pixels seen in all events: 11514943
[15:14:22.691] INFO: Resuming triggers.
[15:14:30.079] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[15:14:30.113] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:14:55.266] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 727702 events.
[15:15:07.759] DEBUG: <PixTestXray.cc/processData:L823> # events read: 727702, pixels seen in all events: 11519105
[15:15:07.970] INFO: Resuming triggers.
[15:15:15.361] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[15:15:15.397] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:15:40.469] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 728051 events.
[15:15:52.851] DEBUG: <PixTestXray.cc/processData:L823> # events read: 728051, pixels seen in all events: 11515641
[15:15:53.062] INFO: Resuming triggers.
[15:16:00.452] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[15:16:00.490] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:16:25.562] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 728000 events.
[15:16:37.942] DEBUG: <PixTestXray.cc/processData:L823> # events read: 728000, pixels seen in all events: 11515466
[15:16:38.153] INFO: Resuming triggers.
[15:16:39.116] INFO: data taking finished, elapsed time: 60 seconds.
[15:16:39.312] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:16:42.758] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 94804 events.
[15:16:44.382] DEBUG: <PixTestXray.cc/processData:L823> # events read: 94804, pixels seen in all events: 1502705
[15:16:44.437] INFO: PixTest:: pg_setup set to default.
[15:16:44.479] INFO: PixTestXray::doPhRun() done
[15:16:44.479] INFO: PixTestXray::doTest() done
[15:17:00.088] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:17:00.089] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 393.9mA
[15:17:00.090] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 472.7mA
[15:17:00.090] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[15:17:00.090] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:17:00.090] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:17:00.090] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:17:00.090] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:17:00.090] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:17:00.090] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:17:00.090] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:17:00.090] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:17:00.090] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:17:00.090] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:17:00.090] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:17:00.091] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:17:00.091] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:17:00.091] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:17:00.091] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:17:00.091] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:17:00.091] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:17:00.091] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:17:00.405] QUIET: Connection to board 43 closed.
[15:17:00.484] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[15:00:27.132] INFO: *** Welcome to pxar ***
[15:00:27.132] INFO: *** Today: 2016/06/28
[15:00:27.157] INFO: *** Version: v1.9.0-814-g7497
[15:00:27.157] INFO: readRocDacs: data/mp443/dacParameters35_C0.dat .. data/mp443/dacParameters35_C15.dat
[15:00:27.158] INFO: readTbmDacs: data/mp443/tbmParameters_C0a.dat .. data/mp443/tbmParameters_C0b.dat
[15:00:27.158] INFO: readMaskFile: data/mp443/defaultMaskFile.dat
[15:00:27.158] INFO: readTrimFile: data/mp443/trimParameters35_C0.dat .. data/mp443/trimParameters35_C15.dat
[15:00:27.312] INFO: clk: 4
[15:00:27.312] INFO: ctr: 4
[15:00:27.312] INFO: sda: 19
[15:00:27.312] INFO: tin: 9
[15:00:27.312] INFO: level: 15
[15:00:27.312] INFO: triggerdelay: 0
[15:00:27.312] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:00:27.312] INFO: Log level: DEBUG
[15:00:27.323] QUIET: Connection to board DTB_WRMNMR opened.
[15:00:27.326] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[15:00:27.329] INFO: RPC call hashes of host and DTB match: 398089610
[15:00:28.930] INFO: DUT info:
[15:00:28.930] INFO: The DUT currently contains the following objects:
[15:00:28.930] INFO: 2 TBM Cores tbm08c (2 ON)
[15:00:28.930] INFO: TBM Core alpha (0): 7 registers set
[15:00:28.930] INFO: TBM Core beta (1): 7 registers set
[15:00:28.931] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:00:28.931] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:00:28.931] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:00:28.931] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:00:28.931] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:00:28.931] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:00:28.931] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:00:28.931] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:00:28.931] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:00:28.932] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:00:28.933] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:00:28.934] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:00:28.936] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31326208
[15:00:28.936] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x28a50d0
[15:00:28.936] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2647360
[15:00:28.936] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f853dd94010
[15:00:28.936] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f8543fff510
[15:00:28.936] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31334400 fPxarMemory = 0x7f853dd94010
[15:00:28.937] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 391.5mA
[15:00:28.938] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 471.9mA
[15:00:28.938] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[15:00:29.435] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2c8fdc0
[15:00:29.500] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:00:29.500] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:00:29.500] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:00:29.506] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:00:29.506] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:00:29.506] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:00:29.515] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:00:29.515] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:00:29.525] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:00:29.525] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:00:29.539] INFO: PixTestCmd::init()
[15:00:29.544] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:00:29.544] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:00:29.544] INFO: readGainPedestalParameters data/mp443/phCalibrationFitErr35_C0.dat .. data/mp443/phCalibrationFitErr35_C15.dat
[15:00:29.788] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:00:29.789] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:00:29.800] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:00:29.807] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:00:29.807] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:00:29.807] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:00:29.826] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:00:29.826] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:00:29.826] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:00:29.826] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:00:29.828] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:00:29.832] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:00:29.833] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:00:29.848] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:00:29.848] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:00:29.848] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:00:29.848] INFO: readReadbackCal: data/mp443/readbackCal_C0.dat .. data/mp443/readbackCal_C15.dat
[15:00:29.857] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:00:29.866] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:00:29.866] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:00:29.866] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:00:29.866] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:00:29.881] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:00:29.881] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:00:33.299] INFO: ######################################################################
[15:00:33.299] INFO: PixTestAlive::doTest()
[15:00:33.299] INFO: ######################################################################
[15:00:33.303] INFO: ----------------------------------------------------------------------
[15:00:33.303] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:00:33.303] INFO: ----------------------------------------------------------------------
[15:00:33.305] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:00:33.686] INFO: Expecting 41600 events.
[15:00:37.888] INFO: 41600 events read in total (3484ms).
[15:00:38.035] INFO: Test took 4730ms.
[15:00:38.045] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:38.045] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66555
[15:00:38.045] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:00:38.318] INFO: PixTestAlive::aliveTest() done
[15:00:38.318] INFO: number of dead pixels (per ROC): 0 0 0 2 1 0 0 0 0 0 0 0 0 0 0 2
[15:00:38.318] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 26 13 20 40 38 34 45 45 36 49 43 32 44 33 22 22
[15:00:38.342] INFO: ----------------------------------------------------------------------
[15:00:38.342] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:00:38.342] INFO: ----------------------------------------------------------------------
[15:00:38.345] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:00:38.724] INFO: Expecting 41600 events.
[15:00:41.660] INFO: 41600 events read in total (2221ms).
[15:00:41.660] INFO: Test took 3315ms.
[15:00:41.660] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:41.660] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:00:41.660] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:00:41.661] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:00:42.083] INFO: PixTestAlive::maskTest() done
[15:00:42.083] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:00:42.105] INFO: ----------------------------------------------------------------------
[15:00:42.105] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:00:42.105] INFO: ----------------------------------------------------------------------
[15:00:42.107] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:00:42.485] INFO: Expecting 41600 events.
[15:00:46.677] INFO: 41600 events read in total (3477ms).
[15:00:46.678] INFO: Test took 4571ms.
[15:00:46.690] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:46.690] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66555
[15:00:46.690] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:00:47.060] INFO: PixTestAlive::addressDecodingTest() done
[15:00:47.060] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:00:47.060] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[15:00:48.576] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:00:48.576] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:00:48.576] INFO: ----------------------------------------------------------------------
[15:00:48.576] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:00:48.576] INFO: ----------------------------------------------------------------------
[15:00:48.715] INFO: Expecting 768 events.
[15:00:49.844] INFO: 768 events read in total (414ms).
[15:00:49.844] INFO: Test took 1262ms.
[15:00:49.850] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:00:50.647] INFO: Expecting 41600 events.
[15:00:53.856] INFO: 41600 events read in total (2682ms).
[15:00:53.861] INFO: Test took 4011ms.
[15:00:53.946] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:53.946] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486716
[15:00:53.946] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:00:53.947] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:00:53.985] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:00:54.650] INFO: Expecting 41600 events.
[15:00:57.984] INFO: 41600 events read in total (2807ms).
[15:00:57.987] INFO: Test took 4002ms.
[15:00:58.068] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:58.068] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 494349
[15:00:58.068] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:00:58.068] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:00:58.108] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:00:58.773] INFO: Expecting 41600 events.
[15:01:02.137] INFO: 41600 events read in total (2838ms).
[15:01:02.141] INFO: Test took 4033ms.
[15:01:02.225] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:02.225] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496218
[15:01:02.225] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:01:02.225] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:02.267] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:02.933] INFO: Expecting 41600 events.
[15:01:06.295] INFO: 41600 events read in total (2835ms).
[15:01:06.298] INFO: Test took 4031ms.
[15:01:06.380] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:06.380] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495250
[15:01:06.380] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:01:06.380] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:06.422] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:07.088] INFO: Expecting 41600 events.
[15:01:10.445] INFO: 41600 events read in total (2830ms).
[15:01:10.449] INFO: Test took 4027ms.
[15:01:10.531] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:10.531] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496281
[15:01:10.531] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:01:10.531] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:10.573] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:11.239] INFO: Expecting 41600 events.
[15:01:14.601] INFO: 41600 events read in total (2835ms).
[15:01:14.605] INFO: Test took 4032ms.
[15:01:14.686] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:14.686] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496306
[15:01:14.686] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:01:14.687] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:14.727] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:15.392] INFO: Expecting 41600 events.
[15:01:18.766] INFO: 41600 events read in total (2847ms).
[15:01:18.770] INFO: Test took 4043ms.
[15:01:18.853] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:18.853] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496396
[15:01:18.853] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:01:18.854] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:18.895] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:19.561] INFO: Expecting 41600 events.
[15:01:22.940] INFO: 41600 events read in total (2852ms).
[15:01:22.944] INFO: Test took 4049ms.
[15:01:23.026] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:23.026] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495979
[15:01:23.026] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:01:23.026] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:23.067] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:23.732] INFO: Expecting 41600 events.
[15:01:27.110] INFO: 41600 events read in total (2852ms).
[15:01:27.114] INFO: Test took 4047ms.
[15:01:27.196] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:27.196] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496504
[15:01:27.196] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:01:27.197] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:27.238] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:27.904] INFO: Expecting 41600 events.
[15:01:31.279] INFO: 41600 events read in total (2848ms).
[15:01:31.283] INFO: Test took 4045ms.
[15:01:31.366] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:31.366] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496275
[15:01:31.366] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:01:31.366] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:31.408] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:32.074] INFO: Expecting 41600 events.
[15:01:35.448] INFO: 41600 events read in total (2847ms).
[15:01:35.452] INFO: Test took 4043ms.
[15:01:35.533] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:35.533] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495202
[15:01:35.533] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:01:35.534] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:35.575] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:36.239] INFO: Expecting 41600 events.
[15:01:39.619] INFO: 41600 events read in total (2853ms).
[15:01:39.623] INFO: Test took 4048ms.
[15:01:39.705] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:39.705] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496201
[15:01:39.705] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:01:39.705] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:39.746] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:40.411] INFO: Expecting 41600 events.
[15:01:43.790] INFO: 41600 events read in total (2853ms).
[15:01:43.794] INFO: Test took 4048ms.
[15:01:43.875] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:43.875] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496520
[15:01:43.875] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:01:43.876] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:43.916] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:44.581] INFO: Expecting 41600 events.
[15:01:47.948] INFO: 41600 events read in total (2840ms).
[15:01:47.952] INFO: Test took 4036ms.
[15:01:48.034] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:48.034] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496012
[15:01:48.034] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:01:48.034] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:48.075] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:48.740] INFO: Expecting 41600 events.
[15:01:52.119] INFO: 41600 events read in total (2852ms).
[15:01:52.123] INFO: Test took 4048ms.
[15:01:52.205] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:52.205] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495600
[15:01:52.205] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:01:52.205] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:52.246] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:52.911] INFO: Expecting 41600 events.
[15:01:56.294] INFO: 41600 events read in total (2856ms).
[15:01:56.298] INFO: Test took 4052ms.
[15:01:56.379] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:56.379] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496005
[15:01:56.379] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:01:56.379] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:56.421] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:57.085] INFO: Expecting 41600 events.
[15:02:00.472] INFO: 41600 events read in total (2860ms).
[15:02:00.475] INFO: Test took 4054ms.
[15:02:00.557] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:00.557] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495032
[15:02:00.557] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:02:00.557] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:00.598] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:01.263] INFO: Expecting 41600 events.
[15:02:04.615] INFO: 41600 events read in total (2825ms).
[15:02:04.619] INFO: Test took 4021ms.
[15:02:04.700] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:04.700] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496361
[15:02:04.700] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:02:04.701] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:04.742] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:05.407] INFO: Expecting 41600 events.
[15:02:08.748] INFO: 41600 events read in total (2814ms).
[15:02:08.751] INFO: Test took 4010ms.
[15:02:08.832] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:08.832] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 494795
[15:02:08.832] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:02:08.833] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:08.873] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:09.539] INFO: Expecting 41600 events.
[15:02:12.754] INFO: 41600 events read in total (2688ms).
[15:02:12.757] INFO: Test took 3884ms.
[15:02:12.838] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:12.838] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 490884
[15:02:12.838] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:02:12.839] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:13.101] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 184 eff = 0.999495
[15:02:13.101] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 164 eff = 0.99976
[15:02:13.101] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 148 eff = 0.999327
[15:02:13.101] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 178 eff = 0.998462
[15:02:13.102] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 176 eff = 0.998365
[15:02:13.102] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 158 eff = 0.998798
[15:02:13.102] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 159 eff = 0.998726
[15:02:13.102] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 159 eff = 0.998726
[15:02:13.102] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 172 eff = 0.998702
[15:02:13.102] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 161 eff = 0.998726
[15:02:13.102] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 178 eff = 0.998654
[15:02:13.103] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 167 eff = 0.998774
[15:02:13.103] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 178 eff = 0.998942
[15:02:13.103] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 180 eff = 0.999351
[15:02:13.103] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 146 eff = 0.999784
[15:02:13.103] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 183 eff = 0.999111
[15:02:18.280] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:02:18.280] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:02:18.280] INFO: ----------------------------------------------------------------------
[15:02:18.280] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:02:18.280] INFO: ----------------------------------------------------------------------
[15:02:18.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:02:18.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:02:18.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:02:18.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:02:18.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:02:18.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:02:18.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:02:18.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:02:18.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:02:18.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:02:18.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:02:18.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:02:18.299] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:18.963] INFO: Expecting 208000 events.
[15:02:32.466] INFO: 208000 events read in total (12976ms).
[15:02:32.478] INFO: Test took 14179ms.
[15:02:32.937] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:32.937] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2202770
[15:02:32.937] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:02:32.938] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:33.317] INFO: number of dead pixels (per ROC): 0 0 0 2 1 0 0 0 0 0 0 0 0 0 0 2
[15:02:33.317] INFO: number of red-efficiency pixels: 104 106 191 234 287 264 288 293 299 311 276 279 216 138 80 82
[15:02:33.317] INFO: number of X-ray hits detected: 78886 61588 101820 157802 168510 167507 169743 159642 175030 177006 173833 157185 155719 102185 60138 69621
[15:02:33.317] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:02:33.317] INFO: number of Vcal hits detected: 207894 207894 207804 207659 207651 207722 207692 207694 207692 207673 207710 207713 207777 207858 207918 207819
[15:02:33.317] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[15:02:33.317] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 99.9
[15:02:33.317] INFO: X-ray hit rate [MHz/cm2]: 23.1 18.1 29.8 46.3 49.4 49.1 49.8 46.8 51.3 51.9 51.0 46.1 45.6 30.0 17.6 20.4
[15:02:33.317] INFO: PixTestHighRate::doXPixelAlive() done
[15:02:33.361] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:02:33.362] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:02:33.362] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:02:33.362] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:02:33.362] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:02:33.362] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:02:33.362] INFO: PixTest:: pg_setup set to default.
[15:03:02.481] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:03:02.481] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[15:03:03.984] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:03:03.985] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 393.9mA
[15:03:03.985] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 471.9mA
[15:03:03.985] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:03:03.985] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:03:03.986] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:03:03.986] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:03:03.986] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:03:03.986] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:03:03.986] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:03:03.986] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:03:03.986] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:03:03.986] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:03:03.986] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:03:04.076] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:03:04.076] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:03:04.085] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:03:04.085] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:03:04.086] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:03:04.086] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:03:04.086] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:03:04.086] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:03:04.089] QUIET: Connection to board 43 closed.
[15:03:04.169] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[14:57:28.325] INFO: *** Welcome to pxar ***
[14:57:28.326] INFO: *** Today: 2016/06/28
[14:57:28.350] INFO: *** Version: v1.9.0-814-g7497
[14:57:28.350] INFO: readRocDacs: data/mp443/dacParameters35_C0.dat .. data/mp443/dacParameters35_C15.dat
[14:57:28.351] INFO: readTbmDacs: data/mp443/tbmParameters_C0a.dat .. data/mp443/tbmParameters_C0b.dat
[14:57:28.351] INFO: readMaskFile: data/mp443/defaultMaskFile.dat
[14:57:28.351] INFO: readTrimFile: data/mp443/trimParameters35_C0.dat .. data/mp443/trimParameters35_C15.dat
[14:57:28.501] INFO: clk: 4
[14:57:28.501] INFO: ctr: 4
[14:57:28.501] INFO: sda: 19
[14:57:28.501] INFO: tin: 9
[14:57:28.501] INFO: level: 15
[14:57:28.501] INFO: triggerdelay: 0
[14:57:28.501] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[14:57:28.501] INFO: Log level: DEBUG
[14:57:28.513] QUIET: Connection to board DTB_WRMNMR opened.
[14:57:28.516] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[14:57:28.519] INFO: RPC call hashes of host and DTB match: 398089610
[14:57:30.122] INFO: DUT info:
[14:57:30.122] INFO: The DUT currently contains the following objects:
[14:57:30.123] INFO: 2 TBM Cores tbm08c (2 ON)
[14:57:30.123] INFO: TBM Core alpha (0): 7 registers set
[14:57:30.123] INFO: TBM Core beta (1): 7 registers set
[14:57:30.123] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:57:30.123] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:30.123] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:57:30.123] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:57:30.124] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:57:30.125] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:57:30.126] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:57:30.127] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:57:30.127] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:57:30.127] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:57:30.127] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:57:30.129] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31358976
[14:57:30.129] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x19ab0d0
[14:57:30.129] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x174d360
[14:57:30.129] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f2a19393010
[14:57:30.129] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f2a1f5fe510
[14:57:30.129] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31367168 fPxarMemory = 0x7f2a19393010
[14:57:30.130] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 392.3mA
[14:57:30.131] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 471.9mA
[14:57:30.131] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[14:57:30.628] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1d95d40
[14:57:30.692] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:57:30.692] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:57:30.692] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:57:30.697] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:57:30.697] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:57:30.697] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:57:30.705] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:57:30.705] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:57:30.722] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:57:30.722] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:57:30.734] INFO: PixTestCmd::init()
[14:57:30.741] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:57:30.741] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:57:30.741] INFO: readGainPedestalParameters data/mp443/phCalibrationFitErr35_C0.dat .. data/mp443/phCalibrationFitErr35_C15.dat
[14:57:30.988] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:57:30.988] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:57:31.001] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:57:31.006] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:57:31.006] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:57:31.007] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:57:31.028] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:57:31.028] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:57:31.029] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:57:31.029] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:57:31.029] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:57:31.035] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:57:31.035] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:57:31.050] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:57:31.050] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:57:31.051] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:57:31.051] INFO: readReadbackCal: data/mp443/readbackCal_C0.dat .. data/mp443/readbackCal_C15.dat
[14:57:31.061] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:57:31.069] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[14:57:31.069] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[14:57:31.069] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[14:57:31.069] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[14:57:31.080] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:57:31.080] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:57:36.363] INFO: ######################################################################
[14:57:36.363] INFO: PixTestAlive::doTest()
[14:57:36.363] INFO: ######################################################################
[14:57:36.367] INFO: ----------------------------------------------------------------------
[14:57:36.367] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:57:36.367] INFO: ----------------------------------------------------------------------
[14:57:36.369] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:57:36.748] INFO: Expecting 41600 events.
[14:57:40.945] INFO: 41600 events read in total (3478ms).
[14:57:41.091] INFO: Test took 4722ms.
[14:57:41.103] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:57:41.103] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66555
[14:57:41.103] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:57:41.377] INFO: PixTestAlive::aliveTest() done
[14:57:41.377] INFO: number of dead pixels (per ROC): 0 0 0 2 1 0 0 0 0 0 0 0 0 0 0 2
[14:57:41.377] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 36 35 44 60 74 54 56 43 66 55 68 60 80 44 16 32
[14:57:41.405] INFO: ----------------------------------------------------------------------
[14:57:41.405] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:57:41.405] INFO: ----------------------------------------------------------------------
[14:57:41.407] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:57:41.784] INFO: Expecting 41600 events.
[14:57:44.713] INFO: 41600 events read in total (2213ms).
[14:57:44.713] INFO: Test took 3306ms.
[14:57:44.713] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:57:44.713] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[14:57:44.713] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[14:57:44.714] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:57:45.136] INFO: PixTestAlive::maskTest() done
[14:57:45.136] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:57:45.158] INFO: ----------------------------------------------------------------------
[14:57:45.158] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:57:45.158] INFO: ----------------------------------------------------------------------
[14:57:45.160] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:57:45.537] INFO: Expecting 41600 events.
[14:57:49.731] INFO: 41600 events read in total (3479ms).
[14:57:49.732] INFO: Test took 4572ms.
[14:57:49.742] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:57:49.742] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66555
[14:57:49.742] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[14:57:50.113] INFO: PixTestAlive::addressDecodingTest() done
[14:57:50.113] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:57:50.113] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[14:58:15.936] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:58:15.936] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C3_V0 -> PixelAlive_mod
[14:58:18.096] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[14:58:18.096] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:58:18.096] INFO: ----------------------------------------------------------------------
[14:58:18.096] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:58:18.096] INFO: ----------------------------------------------------------------------
[14:58:18.235] INFO: Expecting 768 events.
[14:58:19.364] INFO: 768 events read in total (414ms).
[14:58:19.364] INFO: Test took 1263ms.
[14:58:19.370] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:58:20.167] INFO: Expecting 41600 events.
[14:58:23.644] INFO: 41600 events read in total (2950ms).
[14:58:23.649] INFO: Test took 4279ms.
[14:58:23.774] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:23.774] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 700504
[14:58:23.774] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:58:23.774] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:58:23.827] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:58:24.492] INFO: Expecting 41600 events.
[14:58:28.072] INFO: 41600 events read in total (3053ms).
[14:58:28.076] INFO: Test took 4249ms.
[14:58:28.194] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:28.194] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 707341
[14:58:28.194] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:58:28.195] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:58:28.249] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:58:28.914] INFO: Expecting 41600 events.
[14:58:32.501] INFO: 41600 events read in total (3060ms).
[14:58:32.506] INFO: Test took 4257ms.
[14:58:32.625] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:32.625] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708494
[14:58:32.625] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:58:32.625] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:58:32.679] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:58:33.344] INFO: Expecting 41600 events.
[14:58:36.962] INFO: 41600 events read in total (3091ms).
[14:58:36.967] INFO: Test took 4288ms.
[14:58:37.086] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:37.086] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 707373
[14:58:37.086] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:58:37.086] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:58:37.141] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:58:37.806] INFO: Expecting 41600 events.
[14:58:41.422] INFO: 41600 events read in total (3090ms).
[14:58:41.426] INFO: Test took 4285ms.
[14:58:41.546] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:41.546] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 710248
[14:58:41.546] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:58:41.546] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:58:41.601] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:58:42.266] INFO: Expecting 41600 events.
[14:58:45.868] INFO: 41600 events read in total (3075ms).
[14:58:45.873] INFO: Test took 4272ms.
[14:58:45.992] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:45.992] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 709831
[14:58:45.992] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:58:45.993] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:58:46.047] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:58:46.712] INFO: Expecting 41600 events.
[14:58:50.345] INFO: 41600 events read in total (3106ms).
[14:58:50.349] INFO: Test took 4302ms.
[14:58:50.470] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:50.470] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708575
[14:58:50.470] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:58:50.470] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:58:50.525] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:58:51.190] INFO: Expecting 41600 events.
[14:58:54.825] INFO: 41600 events read in total (3108ms).
[14:58:54.829] INFO: Test took 4304ms.
[14:58:54.948] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:54.948] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 709077
[14:58:54.948] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:58:54.948] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:58:55.002] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:58:55.667] INFO: Expecting 41600 events.
[14:58:59.302] INFO: 41600 events read in total (3109ms).
[14:58:59.307] INFO: Test took 4305ms.
[14:58:59.427] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:58:59.427] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708606
[14:58:59.427] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:58:59.427] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:58:59.482] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:59:00.147] INFO: Expecting 41600 events.
[14:59:03.774] INFO: 41600 events read in total (3100ms).
[14:59:03.778] INFO: Test took 4296ms.
[14:59:03.896] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:03.896] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708029
[14:59:03.896] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:59:03.896] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:59:03.951] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:59:04.616] INFO: Expecting 41600 events.
[14:59:08.253] INFO: 41600 events read in total (3110ms).
[14:59:08.258] INFO: Test took 4307ms.
[14:59:08.377] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:08.377] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708526
[14:59:08.377] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:59:08.378] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:59:08.434] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:59:09.099] INFO: Expecting 41600 events.
[14:59:12.740] INFO: 41600 events read in total (3114ms).
[14:59:12.745] INFO: Test took 4311ms.
[14:59:12.864] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:12.864] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708533
[14:59:12.864] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:59:12.864] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:59:12.919] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:59:13.585] INFO: Expecting 41600 events.
[14:59:17.221] INFO: 41600 events read in total (3109ms).
[14:59:17.225] INFO: Test took 4306ms.
[14:59:17.343] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:17.343] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 709707
[14:59:17.343] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:59:17.343] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:59:17.398] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:59:18.063] INFO: Expecting 41600 events.
[14:59:21.679] INFO: 41600 events read in total (3090ms).
[14:59:21.683] INFO: Test took 4285ms.
[14:59:21.799] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:21.800] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 709345
[14:59:21.800] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:59:21.800] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:59:21.855] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:59:22.520] INFO: Expecting 41600 events.
[14:59:26.146] INFO: 41600 events read in total (3099ms).
[14:59:26.150] INFO: Test took 4295ms.
[14:59:26.269] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:26.269] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 709078
[14:59:26.269] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:59:26.269] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:59:26.324] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:59:26.989] INFO: Expecting 41600 events.
[14:59:30.604] INFO: 41600 events read in total (3088ms).
[14:59:30.609] INFO: Test took 4285ms.
[14:59:30.727] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:30.727] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 709663
[14:59:30.727] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:59:30.728] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:59:30.783] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:59:31.448] INFO: Expecting 41600 events.
[14:59:35.069] INFO: 41600 events read in total (3095ms).
[14:59:35.073] INFO: Test took 4290ms.
[14:59:35.192] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:35.192] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708029
[14:59:35.192] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:59:35.192] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:59:35.248] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:59:35.912] INFO: Expecting 41600 events.
[14:59:39.535] INFO: 41600 events read in total (3096ms).
[14:59:39.539] INFO: Test took 4291ms.
[14:59:39.657] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:39.657] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708554
[14:59:39.657] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:59:39.658] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:59:39.712] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:59:40.377] INFO: Expecting 41600 events.
[14:59:43.978] INFO: 41600 events read in total (3074ms).
[14:59:43.982] INFO: Test took 4270ms.
[14:59:44.102] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:44.102] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708155
[14:59:44.102] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:59:44.103] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:59:44.158] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:59:44.823] INFO: Expecting 41600 events.
[14:59:48.256] INFO: 41600 events read in total (2906ms).
[14:59:48.261] INFO: Test took 4103ms.
[14:59:48.375] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:48.375] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 701419
[14:59:48.375] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:59:48.376] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:59:48.651] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 179 eff = 0.999207
[14:59:48.651] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 186 eff = 0.999423
[14:59:48.651] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 157 eff = 0.998582
[14:59:48.652] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 184 eff = 0.997452
[14:59:48.652] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 179 eff = 0.997091
[14:59:48.652] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 158 eff = 0.997356
[14:59:48.652] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 159 eff = 0.997308
[14:59:48.652] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 163 eff = 0.997404
[14:59:48.652] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 171 eff = 0.996995
[14:59:48.652] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 164 eff = 0.997236
[14:59:48.653] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 184 eff = 0.997212
[14:59:48.653] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 176 eff = 0.99774
[14:59:48.653] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 175 eff = 0.998029
[14:59:48.653] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 177 eff = 0.998654
[14:59:48.653] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 167 eff = 0.999567
[14:59:48.653] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 196 eff = 0.998822
[14:59:55.336] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[14:59:55.336] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:59:55.336] INFO: ----------------------------------------------------------------------
[14:59:55.336] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:59:55.336] INFO: ----------------------------------------------------------------------
[14:59:55.336] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:59:55.336] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:59:55.336] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:59:55.336] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:59:55.336] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:59:55.336] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:59:55.336] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:59:55.336] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:59:55.336] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:59:55.336] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:59:55.336] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:59:55.337] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:59:55.355] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:59:56.025] INFO: Expecting 208000 events.
[15:00:11.144] INFO: 208000 events read in total (14593ms).
[15:00:11.161] INFO: Test took 15805ms.
[15:00:11.797] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:00:11.797] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3246981
[15:00:11.797] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:00:11.797] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:00:12.247] INFO: number of dead pixels (per ROC): 0 0 0 2 1 0 0 0 0 0 0 0 0 0 0 2
[15:00:12.248] INFO: number of red-efficiency pixels: 204 127 306 468 557 508 544 517 596 566 564 502 480 281 148 140
[15:00:12.248] INFO: number of X-ray hits detected: 117289 91835 151232 233955 250845 248258 252729 239478 260412 264298 258412 232909 232678 152783 89569 103744
[15:00:12.248] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:00:12.248] INFO: number of Vcal hits detected: 207781 207869 207665 207405 207343 207454 207396 207429 207347 207368 207373 207450 207474 207702 207846 207755
[15:00:12.248] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.7 99.7 99.7 99.7 99.7 99.8 99.8 99.9 99.9 99.9
[15:00:12.248] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.7 99.7 99.7 99.7 99.7 99.7 99.7 99.7 99.9 99.9 99.9
[15:00:12.248] INFO: X-ray hit rate [MHz/cm2]: 34.4 26.9 44.3 68.6 73.5 72.8 74.1 70.2 76.3 77.5 75.7 68.3 68.2 44.8 26.3 30.4
[15:00:12.248] INFO: PixTestHighRate::doXPixelAlive() done
[15:00:12.292] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:00:12.292] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:00:12.292] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:00:12.293] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:00:12.293] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:00:12.293] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:00:12.293] INFO: PixTest:: pg_setup set to default.
[15:00:16.968] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:00:16.968] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[15:00:18.312] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:00:18.313] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 393.9mA
[15:00:18.313] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 472.7mA
[15:00:18.313] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[15:00:18.314] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:00:18.314] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:00:18.314] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:00:18.314] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:00:18.314] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:00:18.314] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:00:18.314] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:00:18.314] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:00:18.314] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:00:18.314] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:00:18.410] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:00:18.410] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:00:18.423] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:00:18.423] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:00:18.423] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:00:18.424] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:00:18.424] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:00:18.424] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:00:18.426] QUIET: Connection to board 43 closed.
[15:00:18.505] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[14:54:31.635] INFO: *** Welcome to pxar ***
[14:54:31.635] INFO: *** Today: 2016/06/28
[14:54:31.659] INFO: *** Version: v1.9.0-814-g7497
[14:54:31.660] INFO: readRocDacs: data/mp443/dacParameters35_C0.dat .. data/mp443/dacParameters35_C15.dat
[14:54:31.661] INFO: readTbmDacs: data/mp443/tbmParameters_C0a.dat .. data/mp443/tbmParameters_C0b.dat
[14:54:31.661] INFO: readMaskFile: data/mp443/defaultMaskFile.dat
[14:54:31.661] INFO: readTrimFile: data/mp443/trimParameters35_C0.dat .. data/mp443/trimParameters35_C15.dat
[14:54:31.812] INFO: clk: 4
[14:54:31.812] INFO: ctr: 4
[14:54:31.812] INFO: sda: 19
[14:54:31.812] INFO: tin: 9
[14:54:31.812] INFO: level: 15
[14:54:31.812] INFO: triggerdelay: 0
[14:54:31.812] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[14:54:31.812] INFO: Log level: DEBUG
[14:54:31.823] QUIET: Connection to board DTB_WRMNMR opened.
[14:54:31.826] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[14:54:31.829] INFO: RPC call hashes of host and DTB match: 398089610
[14:54:33.430] INFO: DUT info:
[14:54:33.431] INFO: The DUT currently contains the following objects:
[14:54:33.431] INFO: 2 TBM Cores tbm08c (2 ON)
[14:54:33.431] INFO: TBM Core alpha (0): 7 registers set
[14:54:33.431] INFO: TBM Core beta (1): 7 registers set
[14:54:33.431] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:54:33.431] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:54:33.431] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:54:33.432] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:54:33.433] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:54:33.434] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:54:33.435] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:54:33.435] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:54:33.435] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:54:33.435] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:54:33.435] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:54:33.435] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:54:33.437] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33005568
[14:54:33.437] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x16080d0
[14:54:33.437] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x13aa360
[14:54:33.437] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fd485393010
[14:54:33.437] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fd48b5fe510
[14:54:33.437] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33013760 fPxarMemory = 0x7fd485393010
[14:54:33.438] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 390.7mA
[14:54:33.439] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 471.9mA
[14:54:33.439] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[14:54:33.939] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x19f2db0
[14:54:34.000] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:54:34.000] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:54:34.000] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:54:34.006] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:54:34.006] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:54:34.006] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:54:34.015] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:54:34.015] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:54:34.029] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:54:34.029] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:54:34.042] INFO: PixTestCmd::init()
[14:54:34.054] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:54:34.054] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:54:34.054] INFO: readGainPedestalParameters data/mp443/phCalibrationFitErr35_C0.dat .. data/mp443/phCalibrationFitErr35_C15.dat
[14:54:34.296] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:54:34.296] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:54:34.310] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:54:34.316] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:54:34.316] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:54:34.316] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:54:34.338] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:54:34.338] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:54:34.338] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:54:34.338] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:54:34.338] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:54:34.345] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:54:34.345] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:54:34.362] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:54:34.362] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:54:34.362] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:54:34.362] INFO: readReadbackCal: data/mp443/readbackCal_C0.dat .. data/mp443/readbackCal_C15.dat
[14:54:34.374] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:54:34.381] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[14:54:34.381] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[14:54:34.381] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[14:54:34.381] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[14:54:34.397] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:54:34.397] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:54:37.763] INFO: ######################################################################
[14:54:37.763] INFO: PixTestAlive::doTest()
[14:54:37.763] INFO: ######################################################################
[14:54:37.766] INFO: ----------------------------------------------------------------------
[14:54:37.766] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:54:37.766] INFO: ----------------------------------------------------------------------
[14:54:37.769] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:38.147] INFO: Expecting 41600 events.
[14:54:42.359] INFO: 41600 events read in total (3494ms).
[14:54:42.505] INFO: Test took 4736ms.
[14:54:42.516] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:42.516] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66555
[14:54:42.516] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:54:42.790] INFO: PixTestAlive::aliveTest() done
[14:54:42.790] INFO: number of dead pixels (per ROC): 0 0 0 2 1 0 0 0 0 0 0 0 0 0 0 2
[14:54:42.790] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 44 34 55 93 106 86 96 83 78 78 104 86 92 49 29 32
[14:54:42.814] INFO: ----------------------------------------------------------------------
[14:54:42.815] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:54:42.815] INFO: ----------------------------------------------------------------------
[14:54:42.817] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:43.195] INFO: Expecting 41600 events.
[14:54:46.117] INFO: 41600 events read in total (2207ms).
[14:54:46.118] INFO: Test took 3301ms.
[14:54:46.118] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:46.118] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[14:54:46.118] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[14:54:46.118] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:54:46.539] INFO: PixTestAlive::maskTest() done
[14:54:46.539] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:54:46.558] INFO: ----------------------------------------------------------------------
[14:54:46.558] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:54:46.558] INFO: ----------------------------------------------------------------------
[14:54:46.560] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:46.937] INFO: Expecting 41600 events.
[14:54:51.137] INFO: 41600 events read in total (3485ms).
[14:54:51.139] INFO: Test took 4579ms.
[14:54:51.150] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:51.150] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66555
[14:54:51.150] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[14:54:51.519] INFO: PixTestAlive::addressDecodingTest() done
[14:54:51.520] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:54:51.520] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[14:54:56.048] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:54:56.048] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[14:54:58.624] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[14:54:58.624] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:54:58.624] INFO: ----------------------------------------------------------------------
[14:54:58.624] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:54:58.624] INFO: ----------------------------------------------------------------------
[14:54:58.763] INFO: Expecting 768 events.
[14:54:59.892] INFO: 768 events read in total (414ms).
[14:54:59.892] INFO: Test took 1263ms.
[14:54:59.898] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:00.695] INFO: Expecting 41600 events.
[14:55:04.512] INFO: 41600 events read in total (3290ms).
[14:55:04.518] INFO: Test took 4620ms.
[14:55:04.687] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:04.687] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 970072
[14:55:04.687] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:55:04.687] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:04.757] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:05.423] INFO: Expecting 41600 events.
[14:55:09.328] INFO: 41600 events read in total (3378ms).
[14:55:09.333] INFO: Test took 4576ms.
[14:55:09.497] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:09.497] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 975211
[14:55:09.497] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:55:09.498] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:09.568] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:10.234] INFO: Expecting 41600 events.
[14:55:14.146] INFO: 41600 events read in total (3383ms).
[14:55:14.152] INFO: Test took 4584ms.
[14:55:14.313] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:14.313] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 978798
[14:55:14.313] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:55:14.313] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:14.383] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:15.048] INFO: Expecting 41600 events.
[14:55:18.976] INFO: 41600 events read in total (3401ms).
[14:55:18.982] INFO: Test took 4599ms.
[14:55:19.144] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:19.144] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 975816
[14:55:19.144] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:55:19.144] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:19.214] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:19.879] INFO: Expecting 41600 events.
[14:55:23.814] INFO: 41600 events read in total (3409ms).
[14:55:23.820] INFO: Test took 4606ms.
[14:55:23.981] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:23.981] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 978526
[14:55:23.981] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:55:23.981] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:24.052] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:24.717] INFO: Expecting 41600 events.
[14:55:28.641] INFO: 41600 events read in total (3398ms).
[14:55:28.648] INFO: Test took 4596ms.
[14:55:28.811] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:28.811] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 979469
[14:55:28.811] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:55:28.812] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:28.885] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:29.551] INFO: Expecting 41600 events.
[14:55:33.505] INFO: 41600 events read in total (3428ms).
[14:55:33.513] INFO: Test took 4628ms.
[14:55:33.673] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:33.673] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 978934
[14:55:33.673] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:55:33.673] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:33.747] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:34.413] INFO: Expecting 41600 events.
[14:55:38.371] INFO: 41600 events read in total (3431ms).
[14:55:38.376] INFO: Test took 4629ms.
[14:55:38.541] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:38.541] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 979132
[14:55:38.541] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:55:38.542] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:38.613] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:39.279] INFO: Expecting 41600 events.
[14:55:43.242] INFO: 41600 events read in total (3436ms).
[14:55:43.247] INFO: Test took 4634ms.
[14:55:43.412] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:43.413] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 981352
[14:55:43.413] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:55:43.413] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:43.485] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:44.151] INFO: Expecting 41600 events.
[14:55:48.099] INFO: 41600 events read in total (3422ms).
[14:55:48.105] INFO: Test took 4620ms.
[14:55:48.268] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:48.268] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 979263
[14:55:48.268] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:55:48.268] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:48.339] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:49.005] INFO: Expecting 41600 events.
[14:55:52.970] INFO: 41600 events read in total (3436ms).
[14:55:52.976] INFO: Test took 4637ms.
[14:55:53.136] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:53.136] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 979632
[14:55:53.136] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:55:53.136] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:53.208] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:53.876] INFO: Expecting 41600 events.
[14:55:57.833] INFO: 41600 events read in total (3431ms).
[14:55:57.839] INFO: Test took 4631ms.
[14:55:58.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:58.002] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 979961
[14:55:58.002] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:55:58.002] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:58.074] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:58.739] INFO: Expecting 41600 events.
[14:56:02.724] INFO: 41600 events read in total (3458ms).
[14:56:02.732] INFO: Test took 4658ms.
[14:56:02.895] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:56:02.895] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 981377
[14:56:02.895] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:56:02.895] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:56:02.967] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:56:03.632] INFO: Expecting 41600 events.
[14:56:07.592] INFO: 41600 events read in total (3433ms).
[14:56:07.598] INFO: Test took 4631ms.
[14:56:07.760] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:56:07.760] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 978972
[14:56:07.760] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:56:07.760] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:56:07.832] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:56:08.497] INFO: Expecting 41600 events.
[14:56:12.457] INFO: 41600 events read in total (3434ms).
[14:56:12.463] INFO: Test took 4631ms.
[14:56:12.623] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:56:12.623] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 978856
[14:56:12.623] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:56:12.624] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:56:12.694] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:56:13.359] INFO: Expecting 41600 events.
[14:56:17.320] INFO: 41600 events read in total (3435ms).
[14:56:17.327] INFO: Test took 4633ms.
[14:56:17.489] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:56:17.489] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 978354
[14:56:17.490] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:56:17.490] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:56:17.562] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:56:18.227] INFO: Expecting 41600 events.
[14:56:22.188] INFO: 41600 events read in total (3434ms).
[14:56:22.194] INFO: Test took 4632ms.
[14:56:22.355] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:56:22.355] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 979758
[14:56:22.355] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:56:22.355] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:56:22.426] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:56:23.090] INFO: Expecting 41600 events.
[14:56:27.041] INFO: 41600 events read in total (3424ms).
[14:56:27.047] INFO: Test took 4621ms.
[14:56:27.209] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:56:27.209] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 978509
[14:56:27.209] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:56:27.209] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:56:27.280] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:56:27.945] INFO: Expecting 41600 events.
[14:56:31.860] INFO: 41600 events read in total (3388ms).
[14:56:31.866] INFO: Test took 4586ms.
[14:56:32.028] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:56:32.028] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 977082
[14:56:32.028] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:56:32.029] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:56:32.100] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:56:32.766] INFO: Expecting 41600 events.
[14:56:36.508] INFO: 41600 events read in total (3214ms).
[14:56:36.514] INFO: Test took 4414ms.
[14:56:36.670] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:56:36.670] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 969760
[14:56:36.670] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:56:36.670] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:56:36.965] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 180 eff = 0.998413
[14:56:36.965] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 181 eff = 0.998678
[14:56:36.965] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 163 eff = 0.997236
[14:56:36.966] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 181 eff = 0.99399
[14:56:36.966] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 176 eff = 0.993558
[14:56:36.966] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 155 eff = 0.994038
[14:56:36.966] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 156 eff = 0.993149
[14:56:36.967] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 161 eff = 0.994639
[14:56:36.967] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 171 eff = 0.993173
[14:56:36.967] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 164 eff = 0.993413
[14:56:36.967] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 179 eff = 0.993486
[14:56:36.968] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 175 eff = 0.994904
[14:56:36.968] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 178 eff = 0.994639
[14:56:36.968] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 177 eff = 0.997284
[14:56:36.968] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 149 eff = 0.998774
[14:56:36.969] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 192 eff = 0.998149
[14:56:57.744] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[14:56:57.744] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:56:57.744] INFO: ----------------------------------------------------------------------
[14:56:57.744] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:56:57.744] INFO: ----------------------------------------------------------------------
[14:56:57.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:56:57.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:56:57.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:56:57.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:56:57.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:56:57.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:56:57.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:56:57.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:56:57.745] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:56:57.745] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:56:57.745] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:56:57.745] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:56:57.762] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:56:58.430] INFO: Expecting 208000 events.
[14:57:16.145] INFO: 208000 events read in total (17188ms).
[14:57:16.168] INFO: Test took 18406ms.
[14:57:17.032] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:57:17.032] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4571776
[14:57:17.032] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:57:17.033] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:57:17.571] INFO: number of dead pixels (per ROC): 0 0 0 2 1 0 0 0 0 0 0 0 0 0 0 2
[14:57:17.571] INFO: number of red-efficiency pixels: 330 238 512 906 1035 1082 1155 983 1083 1149 1118 924 871 526 260 217
[14:57:17.571] INFO: number of X-ray hits detected: 166749 130821 214110 331126 354304 351546 357832 338308 368222 373887 365125 331952 328773 216679 127812 147975
[14:57:17.571] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:57:17.571] INFO: number of Vcal hits detected: 207650 207744 207384 206824 206692 206699 206532 206818 206698 206550 206625 206846 206962 207402 207726 207675
[14:57:17.571] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.5 99.4 99.4 99.5 99.4 99.4 99.4 99.5 99.6 99.7 99.9 99.9
[14:57:17.571] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.4 99.4 99.4 99.3 99.4 99.4 99.3 99.3 99.4 99.5 99.7 99.9 99.8
[14:57:17.571] INFO: X-ray hit rate [MHz/cm2]: 48.9 38.3 62.8 97.1 103.8 103.0 104.9 99.2 107.9 109.6 107.0 97.3 96.4 63.5 37.5 43.4
[14:57:17.571] INFO: PixTestHighRate::doXPixelAlive() done
[14:57:17.616] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:57:17.616] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:57:17.616] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:57:17.616] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:57:17.616] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:57:17.616] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:57:17.616] INFO: PixTest:: pg_setup set to default.
[14:57:21.760] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:57:21.760] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[14:57:23.552] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[14:57:23.553] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 393.9mA
[14:57:23.553] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 472.7mA
[14:57:23.553] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[14:57:23.553] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[14:57:23.553] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[14:57:23.553] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[14:57:23.554] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[14:57:23.554] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[14:57:23.554] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[14:57:23.554] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[14:57:23.554] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[14:57:23.554] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[14:57:23.554] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:57:23.649] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[14:57:23.649] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:57:23.662] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[14:57:23.662] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[14:57:23.662] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[14:57:23.662] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[14:57:23.663] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[14:57:23.663] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:57:23.665] QUIET: Connection to board 43 closed.
[14:57:23.745] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master