Test Date: 1973-11-29 16:33
Analysis date: 2016-06-03 16:17
Logfile
hrData_40.log
[17:32:35.210] INFO: *** Welcome to pxar ***
[17:32:35.210] INFO: *** Today: 2016/06/02
[17:32:35.252] INFO: *** Version: v1.9.0-805-geb36
[17:32:35.252] INFO: readRocDacs: data/mk317/dacParameters35_C0.dat .. data/mk317/dacParameters35_C15.dat
[17:32:35.253] INFO: readTbmDacs: data/mk317/tbmParameters_C0a.dat .. data/mk317/tbmParameters_C0b.dat
[17:32:35.253] INFO: readMaskFile: data/mk317/defaultMaskFile.dat
[17:32:35.253] INFO: readTrimFile: data/mk317/trimParameters35_C0.dat .. data/mk317/trimParameters35_C15.dat
[17:32:35.402] INFO: clk: 4
[17:32:35.402] INFO: ctr: 4
[17:32:35.402] INFO: sda: 19
[17:32:35.402] INFO: tin: 9
[17:32:35.402] INFO: level: 15
[17:32:35.402] INFO: triggerdelay: 0
[17:32:35.402] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:32:35.402] INFO: Log level: DEBUG
[17:32:35.413] QUIET: Connection to board DTB_WRPRHI opened.
[17:32:35.416] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[17:32:35.419] INFO: RPC call hashes of host and DTB match: 398089610
[17:32:37.019] INFO: DUT info:
[17:32:37.019] INFO: The DUT currently contains the following objects:
[17:32:37.019] INFO: 2 TBM Cores tbm08c (2 ON)
[17:32:37.019] INFO: TBM Core alpha (0): 7 registers set
[17:32:37.019] INFO: TBM Core beta (1): 7 registers set
[17:32:37.019] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:32:37.019] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:32:37.019] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:32:37.019] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:32:37.019] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:32:37.019] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:32:37.019] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:32:37.019] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:32:37.019] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:32:37.019] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:32:37.020] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:32:37.021] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:32:37.022] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:32:37.024] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35373056
[17:32:37.024] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1356be0
[17:32:37.024] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x10f8e60
[17:32:37.024] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f8309d94010
[17:32:37.024] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f830ffff510
[17:32:37.024] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35381248 fPxarMemory = 0x7f8309d94010
[17:32:37.025] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 387.5mA
[17:32:37.026] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[17:32:37.026] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[17:32:37.527] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1741800
[17:32:37.587] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:32:37.587] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:32:37.587] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:32:37.593] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:32:37.593] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:32:37.593] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:32:37.607] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:32:37.607] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:32:37.630] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:32:37.630] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:32:37.644] INFO: PixTestCmd::init()
[17:32:37.663] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:32:37.663] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:32:37.663] INFO: readGainPedestalParameters data/mk317/phCalibrationFitErr35_C0.dat .. data/mk317/phCalibrationFitErr35_C15.dat
[17:32:37.904] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:32:37.904] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:32:37.918] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:32:37.925] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:32:37.925] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:32:37.925] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:32:37.949] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:32:37.949] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:32:37.949] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:32:37.949] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:32:37.949] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:32:37.955] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:32:37.955] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:32:37.977] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:32:37.977] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:32:37.977] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:32:37.977] INFO: readReadbackCal: data/mk317/readbackCal_C0.dat .. data/mk317/readbackCal_C15.dat
[17:32:37.986] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:32:37.994] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:32:37.994] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:32:37.994] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:32:37.994] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:32:38.016] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:32:38.017] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:32:43.320] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[17:32:48.128] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[17:32:49.640] INFO: ######################################################################
[17:32:49.641] INFO: PixTestXray::doTest()
[17:32:49.641] INFO: ######################################################################
[17:32:49.641] INFO: ----------------------------------------------------------------------
[17:32:49.641] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[17:32:49.641] INFO: ----------------------------------------------------------------------
[17:32:50.613] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[17:33:02.209] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[17:33:02.213] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:33:26.392] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1142491 events.
[17:33:33.576] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1142491, pixels seen in all events: 6542525
[17:33:33.770] INFO: Resuming triggers.
[17:33:45.373] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[17:33:45.403] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:34:09.493] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1143031 events.
[17:34:16.622] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1143031, pixels seen in all events: 6535269
[17:34:16.705] INFO: Resuming triggers.
[17:34:28.311] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[17:34:28.427] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:34:52.359] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1143350 events.
[17:34:59.506] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1143350, pixels seen in all events: 6530936
[17:34:59.677] INFO: Resuming triggers.
[17:35:11.281] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[17:35:11.308] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:35:35.379] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1143202 events.
[17:35:42.522] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1143202, pixels seen in all events: 6532458
[17:35:42.694] INFO: Resuming triggers.
[17:35:54.306] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[17:35:54.336] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:36:18.262] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1143904 events.
[17:36:25.396] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1143904, pixels seen in all events: 6524781
[17:36:25.569] INFO: Resuming triggers.
[17:36:27.628] INFO: data taking finished, elapsed time: 60 seconds.
[17:36:27.825] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:36:32.232] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 202849 events.
[17:36:33.484] DEBUG: <PixTestXray.cc/processData:L809> # events read: 202849, pixels seen in all events: 1157340
[17:36:33.552] INFO: PixTest:: pg_setup set to default.
[17:36:33.592] INFO: PixTestXray::doPhRun() done
[17:36:33.592] INFO: PixTestXray::doTest() done
[17:37:06.793] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:37:06.793] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 390.7mA
[17:37:06.794] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[17:37:06.794] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[17:37:06.794] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:37:06.794] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:37:06.794] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:37:06.794] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:37:06.794] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:37:06.795] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:37:06.795] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:37:06.795] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:37:06.795] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:37:06.795] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:37:06.795] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:37:06.795] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:37:06.795] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:37:06.795] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:37:06.795] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:37:06.795] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:37:06.795] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:37:06.795] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:37:07.069] QUIET: Connection to board 58 closed.
[17:37:07.150] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[17:25:58.749] INFO: *** Welcome to pxar ***
[17:25:58.749] INFO: *** Today: 2016/06/02
[17:25:58.772] INFO: *** Version: v1.9.0-805-geb36
[17:25:58.772] INFO: readRocDacs: data/mk317/dacParameters35_C0.dat .. data/mk317/dacParameters35_C15.dat
[17:25:58.773] INFO: readTbmDacs: data/mk317/tbmParameters_C0a.dat .. data/mk317/tbmParameters_C0b.dat
[17:25:58.773] INFO: readMaskFile: data/mk317/defaultMaskFile.dat
[17:25:58.773] INFO: readTrimFile: data/mk317/trimParameters35_C0.dat .. data/mk317/trimParameters35_C15.dat
[17:25:58.923] INFO: clk: 4
[17:25:58.923] INFO: ctr: 4
[17:25:58.923] INFO: sda: 19
[17:25:58.923] INFO: tin: 9
[17:25:58.923] INFO: level: 15
[17:25:58.923] INFO: triggerdelay: 0
[17:25:58.923] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:25:58.923] INFO: Log level: DEBUG
[17:25:58.934] QUIET: Connection to board DTB_WRPRHI opened.
[17:25:58.937] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[17:25:58.940] INFO: RPC call hashes of host and DTB match: 398089610
[17:26:00.540] INFO: DUT info:
[17:26:00.540] INFO: The DUT currently contains the following objects:
[17:26:00.540] INFO: 2 TBM Cores tbm08c (2 ON)
[17:26:00.540] INFO: TBM Core alpha (0): 7 registers set
[17:26:00.540] INFO: TBM Core beta (1): 7 registers set
[17:26:00.540] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:26:00.540] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.540] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.540] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.540] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.540] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.540] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.540] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.540] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.540] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.540] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.540] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.540] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.540] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.540] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.540] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.540] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:26:00.541] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:26:00.542] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:26:00.543] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:26:00.545] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31694848
[17:26:00.545] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1294be0
[17:26:00.545] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1036e60
[17:26:00.545] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f6e55d94010
[17:26:00.545] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f6e5bfff510
[17:26:00.545] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31703040 fPxarMemory = 0x7f6e55d94010
[17:26:00.546] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 387.5mA
[17:26:00.548] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[17:26:00.548] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[17:26:01.037] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x167f8c0
[17:26:01.098] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:26:01.098] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:26:01.098] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:26:01.103] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:26:01.104] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:26:01.104] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:26:01.114] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:26:01.114] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:26:01.129] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:26:01.129] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:26:01.142] INFO: PixTestCmd::init()
[17:26:01.152] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:26:01.152] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:26:01.152] INFO: readGainPedestalParameters data/mk317/phCalibrationFitErr35_C0.dat .. data/mk317/phCalibrationFitErr35_C15.dat
[17:26:01.398] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:26:01.398] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:26:01.412] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:26:01.418] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:26:01.418] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:26:01.418] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:26:01.445] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:26:01.445] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:26:01.445] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:26:01.445] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:26:01.445] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:26:01.451] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:26:01.451] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:26:01.472] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:26:01.472] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:26:01.472] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:26:01.472] INFO: readReadbackCal: data/mk317/readbackCal_C0.dat .. data/mk317/readbackCal_C15.dat
[17:26:01.481] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:26:01.489] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:26:01.489] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:26:01.489] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:26:01.489] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:26:01.509] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:26:01.509] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:26:06.696] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[17:26:16.032] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[17:26:17.720] INFO: ######################################################################
[17:26:17.720] INFO: PixTestXray::doTest()
[17:26:17.720] INFO: ######################################################################
[17:26:17.720] INFO: ----------------------------------------------------------------------
[17:26:17.720] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[17:26:17.721] INFO: ----------------------------------------------------------------------
[17:26:18.694] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[17:26:25.919] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[17:26:25.923] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:26:48.522] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 711943 events.
[17:27:00.948] DEBUG: <PixTestXray.cc/processData:L809> # events read: 711943, pixels seen in all events: 11710042
[17:27:01.162] INFO: Resuming triggers.
[17:27:08.390] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[17:27:08.419] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:27:30.915] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 712048 events.
[17:27:43.344] DEBUG: <PixTestXray.cc/processData:L809> # events read: 712048, pixels seen in all events: 11707824
[17:27:43.521] INFO: Resuming triggers.
[17:27:50.751] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[17:27:50.804] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:28:13.364] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 712263 events.
[17:28:24.922] DEBUG: <PixTestXray.cc/processData:L809> # events read: 712263, pixels seen in all events: 11705371
[17:28:25.119] INFO: Resuming triggers.
[17:28:32.355] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[17:28:32.384] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:28:54.961] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 712819 events.
[17:29:06.510] DEBUG: <PixTestXray.cc/processData:L809> # events read: 712819, pixels seen in all events: 11698254
[17:29:06.681] INFO: Resuming triggers.
[17:29:13.917] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[17:29:13.955] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:29:36.484] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 712855 events.
[17:29:48.006] DEBUG: <PixTestXray.cc/processData:L809> # events read: 712855, pixels seen in all events: 11697863
[17:29:48.173] INFO: Resuming triggers.
[17:29:55.409] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[17:29:55.463] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:30:18.104] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 712851 events.
[17:30:29.646] DEBUG: <PixTestXray.cc/processData:L809> # events read: 712851, pixels seen in all events: 11698043
[17:30:29.835] INFO: Resuming triggers.
[17:30:37.071] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[17:30:37.100] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:30:59.766] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 712853 events.
[17:31:11.259] DEBUG: <PixTestXray.cc/processData:L809> # events read: 712853, pixels seen in all events: 11698420
[17:31:11.447] INFO: Resuming triggers.
[17:31:18.685] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[17:31:18.718] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:31:41.269] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 713000 events.
[17:31:52.687] DEBUG: <PixTestXray.cc/processData:L809> # events read: 713000, pixels seen in all events: 11696154
[17:31:52.857] INFO: Resuming triggers.
[17:31:55.070] INFO: data taking finished, elapsed time: 60 seconds.
[17:31:55.266] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:32:02.240] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 217923 events.
[17:32:05.738] DEBUG: <PixTestXray.cc/processData:L809> # events read: 217923, pixels seen in all events: 3577196
[17:32:05.802] INFO: PixTest:: pg_setup set to default.
[17:32:05.841] INFO: PixTestXray::doPhRun() done
[17:32:05.841] INFO: PixTestXray::doTest() done
[17:32:22.384] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:32:22.385] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 390.7mA
[17:32:22.386] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.1mA
[17:32:22.386] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[17:32:22.386] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:32:22.386] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:32:22.386] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:32:22.386] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:32:22.386] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:32:22.386] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:32:22.387] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:32:22.387] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:32:22.387] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:32:22.387] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:32:22.387] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:32:22.387] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:32:22.387] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:32:22.387] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:32:22.387] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:32:22.387] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:32:22.387] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:32:22.388] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:32:22.706] QUIET: Connection to board 58 closed.
[17:32:22.793] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[17:19:41.661] INFO: *** Welcome to pxar ***
[17:19:41.661] INFO: *** Today: 2016/06/02
[17:19:41.684] INFO: *** Version: v1.9.0-805-geb36
[17:19:41.684] INFO: readRocDacs: data/mk317/dacParameters35_C0.dat .. data/mk317/dacParameters35_C15.dat
[17:19:41.685] INFO: readTbmDacs: data/mk317/tbmParameters_C0a.dat .. data/mk317/tbmParameters_C0b.dat
[17:19:41.685] INFO: readMaskFile: data/mk317/defaultMaskFile.dat
[17:19:41.685] INFO: readTrimFile: data/mk317/trimParameters35_C0.dat .. data/mk317/trimParameters35_C15.dat
[17:19:41.836] INFO: clk: 4
[17:19:41.836] INFO: ctr: 4
[17:19:41.836] INFO: sda: 19
[17:19:41.836] INFO: tin: 9
[17:19:41.836] INFO: level: 15
[17:19:41.836] INFO: triggerdelay: 0
[17:19:41.836] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:19:41.836] INFO: Log level: DEBUG
[17:19:41.847] QUIET: Connection to board DTB_WRPRHI opened.
[17:19:41.850] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[17:19:41.853] INFO: RPC call hashes of host and DTB match: 398089610
[17:19:43.452] INFO: DUT info:
[17:19:43.453] INFO: The DUT currently contains the following objects:
[17:19:43.453] INFO: 2 TBM Cores tbm08c (2 ON)
[17:19:43.453] INFO: TBM Core alpha (0): 7 registers set
[17:19:43.453] INFO: TBM Core beta (1): 7 registers set
[17:19:43.453] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:19:43.453] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:43.453] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:19:43.453] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:19:43.453] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:19:43.453] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:19:43.453] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:19:43.453] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:19:43.453] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:19:43.453] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:19:43.453] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:19:43.453] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:19:43.453] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:19:43.453] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:19:43.453] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:19:43.453] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:19:43.454] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:19:43.455] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:19:43.456] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:19:43.456] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:19:43.456] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:19:43.456] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:19:43.456] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:19:43.456] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:19:43.456] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:19:43.456] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:19:43.456] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:19:43.456] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:19:43.456] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:19:43.456] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:19:43.456] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:19:43.456] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:19:43.456] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:19:43.458] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31387648
[17:19:43.458] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x25cdbe0
[17:19:43.458] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x236fe60
[17:19:43.458] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f794dd94010
[17:19:43.458] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f7953fff510
[17:19:43.458] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31395840 fPxarMemory = 0x7f794dd94010
[17:19:43.459] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 387.5mA
[17:19:43.460] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[17:19:43.460] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[17:19:43.948] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x29b8730
[17:19:44.008] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:19:44.008] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:19:44.009] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:19:44.014] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:19:44.014] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:19:44.014] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:19:44.025] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:19:44.025] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:19:44.042] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:19:44.042] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:19:44.065] INFO: PixTestCmd::init()
[17:19:44.081] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:19:44.081] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:19:44.081] INFO: readGainPedestalParameters data/mk317/phCalibrationFitErr35_C0.dat .. data/mk317/phCalibrationFitErr35_C15.dat
[17:19:44.323] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:19:44.323] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:19:44.337] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:19:44.343] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:19:44.343] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:19:44.343] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:19:44.369] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:19:44.369] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:19:44.369] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:19:44.369] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:19:44.369] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:19:44.375] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:19:44.375] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:19:44.398] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:19:44.398] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:19:44.398] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:19:44.398] INFO: readReadbackCal: data/mk317/readbackCal_C0.dat .. data/mk317/readbackCal_C15.dat
[17:19:44.407] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:19:44.415] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:19:44.415] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:19:44.415] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:19:44.415] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:19:44.436] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:19:44.436] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:19:50.491] INFO: ######################################################################
[17:19:50.492] INFO: PixTestAlive::doTest()
[17:19:50.492] INFO: ######################################################################
[17:19:50.495] INFO: ----------------------------------------------------------------------
[17:19:50.495] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:19:50.495] INFO: ----------------------------------------------------------------------
[17:19:50.497] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:19:50.873] INFO: Expecting 41600 events.
[17:19:55.165] INFO: 41600 events read in total (3573ms).
[17:19:55.313] INFO: Test took 4816ms.
[17:19:55.322] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:19:55.322] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[17:19:55.322] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[17:19:55.599] INFO: PixTestAlive::aliveTest() done
[17:19:55.599] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:19:55.599] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 23 19 21 48 47 38 52 56 38 48 43 42 48 25 20 23
[17:19:55.624] INFO: ----------------------------------------------------------------------
[17:19:55.624] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:19:55.624] INFO: ----------------------------------------------------------------------
[17:19:55.626] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:19:56.000] INFO: Expecting 41600 events.
[17:19:59.048] INFO: 41600 events read in total (2332ms).
[17:19:59.049] INFO: Test took 3423ms.
[17:19:59.049] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:19:59.049] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[17:19:59.049] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[17:19:59.049] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:19:59.471] INFO: PixTestAlive::maskTest() done
[17:19:59.471] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:19:59.490] INFO: ----------------------------------------------------------------------
[17:19:59.490] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:19:59.490] INFO: ----------------------------------------------------------------------
[17:19:59.492] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:19:59.868] INFO: Expecting 41600 events.
[17:20:04.130] INFO: 41600 events read in total (3546ms).
[17:20:04.132] INFO: Test took 4640ms.
[17:20:04.142] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:04.142] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[17:20:04.142] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[17:20:04.515] INFO: PixTestAlive::addressDecodingTest() done
[17:20:04.515] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:20:04.515] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[17:20:38.688] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[17:20:38.688] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[17:20:38.688] INFO: ----------------------------------------------------------------------
[17:20:38.689] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:20:38.689] INFO: ----------------------------------------------------------------------
[17:20:38.828] INFO: Expecting 768 events.
[17:20:39.960] INFO: 768 events read in total (417ms).
[17:20:39.961] INFO: Test took 1267ms.
[17:20:39.966] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:40.764] INFO: Expecting 41600 events.
[17:20:44.062] INFO: 41600 events read in total (2771ms).
[17:20:44.066] INFO: Test took 4100ms.
[17:20:44.167] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:44.167] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 534159
[17:20:44.167] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[17:20:44.167] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:44.211] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:44.879] INFO: Expecting 41600 events.
[17:20:48.265] INFO: 41600 events read in total (2859ms).
[17:20:48.271] INFO: Test took 4060ms.
[17:20:48.368] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:48.368] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 543344
[17:20:48.368] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[17:20:48.368] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:48.413] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:49.080] INFO: Expecting 41600 events.
[17:20:52.492] INFO: 41600 events read in total (2885ms).
[17:20:52.497] INFO: Test took 4084ms.
[17:20:52.592] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:52.592] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 541739
[17:20:52.592] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[17:20:52.592] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:52.636] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:53.301] INFO: Expecting 41600 events.
[17:20:56.720] INFO: 41600 events read in total (2892ms).
[17:20:56.724] INFO: Test took 4088ms.
[17:20:56.819] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:56.819] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 543060
[17:20:56.819] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[17:20:56.819] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:56.865] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:57.532] INFO: Expecting 41600 events.
[17:21:00.957] INFO: 41600 events read in total (2898ms).
[17:21:00.961] INFO: Test took 4096ms.
[17:21:01.054] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:01.054] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 543650
[17:21:01.054] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[17:21:01.055] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:01.099] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:01.763] INFO: Expecting 41600 events.
[17:21:05.180] INFO: 41600 events read in total (2890ms).
[17:21:05.184] INFO: Test took 4085ms.
[17:21:05.279] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:05.279] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 543591
[17:21:05.279] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[17:21:05.280] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:05.325] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:05.992] INFO: Expecting 41600 events.
[17:21:09.426] INFO: 41600 events read in total (2907ms).
[17:21:09.430] INFO: Test took 4105ms.
[17:21:09.523] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:09.523] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 543381
[17:21:09.524] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[17:21:09.524] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:09.569] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:10.236] INFO: Expecting 41600 events.
[17:21:13.661] INFO: 41600 events read in total (2898ms).
[17:21:13.665] INFO: Test took 4096ms.
[17:21:13.759] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:13.759] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 543108
[17:21:13.759] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[17:21:13.759] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:13.805] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:14.472] INFO: Expecting 41600 events.
[17:21:17.901] INFO: 41600 events read in total (2903ms).
[17:21:17.905] INFO: Test took 4100ms.
[17:21:18.000] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:18.000] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 544253
[17:21:18.000] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[17:21:18.000] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:18.046] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:18.713] INFO: Expecting 41600 events.
[17:21:22.137] INFO: 41600 events read in total (2897ms).
[17:21:22.141] INFO: Test took 4095ms.
[17:21:22.236] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:22.236] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 542813
[17:21:22.236] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[17:21:22.236] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:22.282] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:22.949] INFO: Expecting 41600 events.
[17:21:26.384] INFO: 41600 events read in total (2908ms).
[17:21:26.388] INFO: Test took 4106ms.
[17:21:26.482] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:26.483] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 542830
[17:21:26.483] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[17:21:26.483] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:26.528] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:27.195] INFO: Expecting 41600 events.
[17:21:30.628] INFO: 41600 events read in total (2906ms).
[17:21:30.632] INFO: Test took 4104ms.
[17:21:30.725] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:30.725] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 543869
[17:21:30.725] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[17:21:30.725] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:30.769] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:31.434] INFO: Expecting 41600 events.
[17:21:34.863] INFO: 41600 events read in total (2903ms).
[17:21:34.867] INFO: Test took 4098ms.
[17:21:34.961] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:34.961] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 543792
[17:21:34.961] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[17:21:34.961] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:35.007] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:35.674] INFO: Expecting 41600 events.
[17:21:39.103] INFO: 41600 events read in total (2902ms).
[17:21:39.107] INFO: Test took 4100ms.
[17:21:39.203] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:39.203] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 543113
[17:21:39.203] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[17:21:39.203] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:39.249] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:39.916] INFO: Expecting 41600 events.
[17:21:43.342] INFO: 41600 events read in total (2899ms).
[17:21:43.346] INFO: Test took 4097ms.
[17:21:43.439] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:43.439] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 543627
[17:21:43.439] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[17:21:43.439] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:43.483] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:44.148] INFO: Expecting 41600 events.
[17:21:47.581] INFO: 41600 events read in total (2907ms).
[17:21:47.585] INFO: Test took 4102ms.
[17:21:47.679] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:47.679] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 543524
[17:21:47.679] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[17:21:47.680] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:47.724] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:48.389] INFO: Expecting 41600 events.
[17:21:51.823] INFO: 41600 events read in total (2908ms).
[17:21:51.827] INFO: Test took 4103ms.
[17:21:51.920] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:51.920] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 542808
[17:21:51.920] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[17:21:51.921] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:51.965] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:52.630] INFO: Expecting 41600 events.
[17:21:56.061] INFO: 41600 events read in total (2905ms).
[17:21:56.065] INFO: Test took 4100ms.
[17:21:56.158] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:56.158] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 543253
[17:21:56.158] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[17:21:56.158] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:56.202] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:56.867] INFO: Expecting 41600 events.
[17:22:00.304] INFO: 41600 events read in total (2910ms).
[17:22:00.308] INFO: Test took 4106ms.
[17:22:00.402] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:22:00.402] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 542501
[17:22:00.402] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[17:22:00.402] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:22:00.446] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:22:01.111] INFO: Expecting 41600 events.
[17:22:04.439] INFO: 41600 events read in total (2801ms).
[17:22:04.443] INFO: Test took 3997ms.
[17:22:04.533] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:22:04.533] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 539714
[17:22:04.533] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[17:22:04.534] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:22:04.799] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 164 eff = 0.999279
[17:22:04.799] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 167 eff = 0.999712
[17:22:04.799] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 128 eff = 0.999135
[17:22:04.799] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 139 eff = 0.998726
[17:22:04.799] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 128 eff = 0.998774
[17:22:04.799] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 164 eff = 0.998606
[17:22:04.800] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 171 eff = 0.998486
[17:22:04.800] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 156 eff = 0.998918
[17:22:04.800] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 151 eff = 0.998726
[17:22:04.800] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 168 eff = 0.998365
[17:22:04.800] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 168 eff = 0.998726
[17:22:04.800] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 157 eff = 0.998774
[17:22:04.800] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 159 eff = 0.998726
[17:22:04.801] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 146 eff = 0.999375
[17:22:04.801] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 153 eff = 0.999591
[17:22:04.801] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 189 eff = 0.999615
[17:22:07.880] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[17:22:07.880] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[17:22:07.880] INFO: ----------------------------------------------------------------------
[17:22:07.880] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:22:07.880] INFO: ----------------------------------------------------------------------
[17:22:07.881] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[17:22:07.881] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[17:22:07.881] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[17:22:07.881] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[17:22:07.881] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[17:22:07.881] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[17:22:07.881] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[17:22:07.881] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[17:22:07.881] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[17:22:07.881] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[17:22:07.881] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[17:22:07.881] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[17:22:07.896] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:22:08.561] INFO: Expecting 208000 events.
[17:22:21.687] INFO: 208000 events read in total (12599ms).
[17:22:21.700] INFO: Test took 13804ms.
[17:22:22.130] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:22:22.130] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2433997
[17:22:22.130] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[17:22:22.130] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:22:22.522] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:22:22.522] INFO: number of red-efficiency pixels: 132 68 207 285 362 286 340 240 279 360 298 330 266 194 94 110
[17:22:22.522] INFO: number of X-ray hits detected: 103208 68444 116001 175429 184907 185097 189522 162580 166230 191926 188767 178591 181372 118312 71314 85738
[17:22:22.522] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:22:22.522] INFO: number of Vcal hits detected: 207817 207924 207777 207703 207624 207695 207648 207750 207709 207624 207691 207654 207719 207800 207904 207886
[17:22:22.522] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.9 99.8 99.9 99.9 99.8 99.9 99.8 99.9 99.9 100.0 99.9
[17:22:22.522] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.9 99.8 99.9 99.9 99.8 99.9 99.8 99.9 99.9 100.0 99.9
[17:22:22.522] INFO: X-ray hit rate [MHz/cm2]: 30.3 20.1 34.0 51.4 54.2 54.3 55.6 47.7 48.7 56.3 55.3 52.3 53.2 34.7 20.9 25.1
[17:22:22.522] INFO: PixTestHighRate::doXPixelAlive() done
[17:22:22.567] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[17:22:22.567] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[17:22:22.567] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[17:22:22.567] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[17:22:22.567] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[17:22:22.567] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[17:22:22.567] INFO: PixTest:: pg_setup set to default.
[17:22:31.312] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:22:31.313] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[17:22:31.314] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[17:22:31.314] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[17:22:31.314] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:22:31.314] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:22:31.314] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:22:31.314] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:22:31.314] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:22:31.314] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:22:31.314] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:22:31.314] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:22:31.314] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:22:31.315] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:22:31.394] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:22:31.394] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:22:31.403] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:22:31.403] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:22:31.403] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:22:31.403] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:22:31.403] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:22:31.403] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:22:31.405] QUIET: Connection to board 58 closed.
[17:22:31.485] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[17:16:25.342] INFO: *** Welcome to pxar ***
[17:16:25.342] INFO: *** Today: 2016/06/02
[17:16:25.364] INFO: *** Version: v1.9.0-805-geb36
[17:16:25.364] INFO: readRocDacs: data/mk317/dacParameters35_C0.dat .. data/mk317/dacParameters35_C15.dat
[17:16:25.365] INFO: readTbmDacs: data/mk317/tbmParameters_C0a.dat .. data/mk317/tbmParameters_C0b.dat
[17:16:25.365] INFO: readMaskFile: data/mk317/defaultMaskFile.dat
[17:16:25.366] INFO: readTrimFile: data/mk317/trimParameters35_C0.dat .. data/mk317/trimParameters35_C15.dat
[17:16:25.515] INFO: clk: 4
[17:16:25.515] INFO: ctr: 4
[17:16:25.515] INFO: sda: 19
[17:16:25.515] INFO: tin: 9
[17:16:25.515] INFO: level: 15
[17:16:25.515] INFO: triggerdelay: 0
[17:16:25.515] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:16:25.516] INFO: Log level: DEBUG
[17:16:25.526] QUIET: Connection to board DTB_WRPRHI opened.
[17:16:25.529] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[17:16:25.532] INFO: RPC call hashes of host and DTB match: 398089610
[17:16:27.132] INFO: DUT info:
[17:16:27.132] INFO: The DUT currently contains the following objects:
[17:16:27.132] INFO: 2 TBM Cores tbm08c (2 ON)
[17:16:27.132] INFO: TBM Core alpha (0): 7 registers set
[17:16:27.132] INFO: TBM Core beta (1): 7 registers set
[17:16:27.132] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:16:27.132] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.132] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.132] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.132] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.132] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.132] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.132] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.132] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.132] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.132] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.132] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.132] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.133] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.133] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.133] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.133] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:16:27.133] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:16:27.134] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:16:27.135] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:16:27.137] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32772096
[17:16:27.137] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xffabe0
[17:16:27.137] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xd9ce60
[17:16:27.137] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f528dd94010
[17:16:27.137] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f5293fff510
[17:16:27.137] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32780288 fPxarMemory = 0x7f528dd94010
[17:16:27.139] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 387.5mA
[17:16:27.140] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[17:16:27.140] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[17:16:27.627] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x13e58d0
[17:16:27.688] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:16:27.688] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:16:27.688] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:16:27.694] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:16:27.694] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:16:27.694] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:16:27.706] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:16:27.706] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:16:27.727] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:16:27.727] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:16:27.745] INFO: PixTestCmd::init()
[17:16:27.751] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:16:27.751] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:16:27.751] INFO: readGainPedestalParameters data/mk317/phCalibrationFitErr35_C0.dat .. data/mk317/phCalibrationFitErr35_C15.dat
[17:16:27.993] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:16:27.993] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:16:28.006] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:16:28.017] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:16:28.017] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:16:28.017] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:16:28.046] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:16:28.046] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:16:28.047] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:16:28.047] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:16:28.047] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:16:28.053] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:16:28.053] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:16:28.075] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:16:28.075] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:16:28.075] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:16:28.075] INFO: readReadbackCal: data/mk317/readbackCal_C0.dat .. data/mk317/readbackCal_C15.dat
[17:16:28.085] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:16:28.101] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:16:28.101] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:16:28.101] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:16:28.101] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:16:28.114] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:16:28.114] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:16:31.820] INFO: ######################################################################
[17:16:31.820] INFO: PixTestAlive::doTest()
[17:16:31.820] INFO: ######################################################################
[17:16:31.823] INFO: ----------------------------------------------------------------------
[17:16:31.823] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:16:31.823] INFO: ----------------------------------------------------------------------
[17:16:31.825] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:16:32.201] INFO: Expecting 41600 events.
[17:16:36.459] INFO: 41600 events read in total (3540ms).
[17:16:36.607] INFO: Test took 4782ms.
[17:16:36.616] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:36.616] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[17:16:36.616] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[17:16:36.892] INFO: PixTestAlive::aliveTest() done
[17:16:36.892] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:16:36.893] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 35 21 50 62 77 67 79 67 58 67 62 78 65 52 27 36
[17:16:36.914] INFO: ----------------------------------------------------------------------
[17:16:36.914] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:16:36.914] INFO: ----------------------------------------------------------------------
[17:16:36.917] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:16:37.290] INFO: Expecting 41600 events.
[17:16:40.339] INFO: 41600 events read in total (2333ms).
[17:16:40.339] INFO: Test took 3422ms.
[17:16:40.339] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:40.339] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[17:16:40.339] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[17:16:40.340] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:16:40.763] INFO: PixTestAlive::maskTest() done
[17:16:40.763] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:16:40.781] INFO: ----------------------------------------------------------------------
[17:16:40.781] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:16:40.781] INFO: ----------------------------------------------------------------------
[17:16:40.783] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:16:41.157] INFO: Expecting 41600 events.
[17:16:45.409] INFO: 41600 events read in total (3536ms).
[17:16:45.410] INFO: Test took 4627ms.
[17:16:45.421] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:45.421] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[17:16:45.421] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[17:16:45.793] INFO: PixTestAlive::addressDecodingTest() done
[17:16:45.793] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:16:45.793] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[17:16:56.392] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[17:16:56.392] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[17:16:56.392] INFO: ----------------------------------------------------------------------
[17:16:56.392] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:16:56.392] INFO: ----------------------------------------------------------------------
[17:16:56.531] INFO: Expecting 768 events.
[17:16:57.664] INFO: 768 events read in total (417ms).
[17:16:57.664] INFO: Test took 1266ms.
[17:16:57.670] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:16:58.467] INFO: Expecting 41600 events.
[17:17:01.987] INFO: 41600 events read in total (2993ms).
[17:17:01.991] INFO: Test took 4321ms.
[17:17:02.124] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:02.124] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 758634
[17:17:02.124] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[17:17:02.125] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:02.183] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:02.851] INFO: Expecting 41600 events.
[17:17:06.478] INFO: 41600 events read in total (3100ms).
[17:17:06.483] INFO: Test took 4300ms.
[17:17:06.610] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:06.610] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 767713
[17:17:06.610] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[17:17:06.611] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:06.669] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:07.334] INFO: Expecting 41600 events.
[17:17:10.984] INFO: 41600 events read in total (3123ms).
[17:17:10.989] INFO: Test took 4320ms.
[17:17:11.118] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:11.118] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 768813
[17:17:11.118] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[17:17:11.119] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:11.179] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:11.847] INFO: Expecting 41600 events.
[17:17:15.495] INFO: 41600 events read in total (3121ms).
[17:17:15.500] INFO: Test took 4321ms.
[17:17:15.629] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:15.629] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 768958
[17:17:15.629] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[17:17:15.629] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:15.687] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:16.352] INFO: Expecting 41600 events.
[17:17:20.005] INFO: 41600 events read in total (3126ms).
[17:17:20.010] INFO: Test took 4323ms.
[17:17:20.138] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:20.138] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 767378
[17:17:20.138] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[17:17:20.138] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:20.198] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:20.865] INFO: Expecting 41600 events.
[17:17:24.532] INFO: 41600 events read in total (3140ms).
[17:17:24.537] INFO: Test took 4339ms.
[17:17:24.665] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:24.665] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 770773
[17:17:24.665] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[17:17:24.665] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:24.727] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:25.394] INFO: Expecting 41600 events.
[17:17:29.067] INFO: 41600 events read in total (3147ms).
[17:17:29.072] INFO: Test took 4345ms.
[17:17:29.202] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:29.202] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 769716
[17:17:29.202] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[17:17:29.203] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:29.264] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:29.931] INFO: Expecting 41600 events.
[17:17:33.602] INFO: 41600 events read in total (3145ms).
[17:17:33.607] INFO: Test took 4343ms.
[17:17:33.737] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:33.737] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 768755
[17:17:33.737] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[17:17:33.737] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:33.797] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:34.464] INFO: Expecting 41600 events.
[17:17:38.152] INFO: 41600 events read in total (3161ms).
[17:17:38.157] INFO: Test took 4360ms.
[17:17:38.287] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:38.287] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 770396
[17:17:38.287] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[17:17:38.287] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:38.346] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:39.011] INFO: Expecting 41600 events.
[17:17:42.679] INFO: 41600 events read in total (3141ms).
[17:17:42.684] INFO: Test took 4337ms.
[17:17:42.814] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:42.814] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 768440
[17:17:42.815] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[17:17:42.815] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:42.875] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:43.542] INFO: Expecting 41600 events.
[17:17:47.223] INFO: 41600 events read in total (3155ms).
[17:17:47.227] INFO: Test took 4352ms.
[17:17:47.357] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:47.357] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 771276
[17:17:47.357] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[17:17:47.357] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:47.415] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:48.080] INFO: Expecting 41600 events.
[17:17:51.752] INFO: 41600 events read in total (3145ms).
[17:17:51.757] INFO: Test took 4342ms.
[17:17:51.885] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:51.885] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 770617
[17:17:51.885] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[17:17:51.886] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:51.944] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:52.609] INFO: Expecting 41600 events.
[17:17:56.283] INFO: 41600 events read in total (3147ms).
[17:17:56.288] INFO: Test took 4343ms.
[17:17:56.417] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:56.417] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 769560
[17:17:56.417] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[17:17:56.417] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:56.476] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:57.141] INFO: Expecting 41600 events.
[17:18:00.807] INFO: 41600 events read in total (3140ms).
[17:18:00.811] INFO: Test took 4335ms.
[17:18:00.940] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:18:00.940] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 769571
[17:18:00.940] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[17:18:00.940] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:18:01.001] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:18:01.668] INFO: Expecting 41600 events.
[17:18:05.344] INFO: 41600 events read in total (3150ms).
[17:18:05.349] INFO: Test took 4348ms.
[17:18:05.479] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:18:05.479] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 769715
[17:18:05.479] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[17:18:05.480] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:18:05.541] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:18:06.207] INFO: Expecting 41600 events.
[17:18:09.877] INFO: 41600 events read in total (3143ms).
[17:18:09.882] INFO: Test took 4341ms.
[17:18:10.009] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:18:10.009] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 768010
[17:18:10.009] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[17:18:10.009] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:18:10.068] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:18:10.733] INFO: Expecting 41600 events.
[17:18:14.401] INFO: 41600 events read in total (3142ms).
[17:18:14.406] INFO: Test took 4338ms.
[17:18:14.535] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:18:14.535] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 767626
[17:18:14.535] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[17:18:14.536] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:18:14.594] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:18:15.259] INFO: Expecting 41600 events.
[17:18:18.928] INFO: 41600 events read in total (3142ms).
[17:18:18.933] INFO: Test took 4339ms.
[17:18:19.062] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:18:19.062] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 768681
[17:18:19.062] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[17:18:19.063] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:18:19.123] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:18:19.790] INFO: Expecting 41600 events.
[17:18:23.456] INFO: 41600 events read in total (3140ms).
[17:18:23.461] INFO: Test took 4338ms.
[17:18:23.588] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:18:23.588] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 768407
[17:18:23.588] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[17:18:23.589] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:18:23.650] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:18:24.317] INFO: Expecting 41600 events.
[17:18:27.870] INFO: 41600 events read in total (3026ms).
[17:18:27.875] INFO: Test took 4225ms.
[17:18:28.001] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:18:28.001] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 763329
[17:18:28.001] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[17:18:28.002] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:18:28.284] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 168 eff = 0.998654
[17:18:28.284] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 167 eff = 0.999447
[17:18:28.285] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 154 eff = 0.998389
[17:18:28.285] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 142 eff = 0.997476
[17:18:28.285] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 141 eff = 0.996418
[17:18:28.285] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 163 eff = 0.996803
[17:18:28.285] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 169 eff = 0.996899
[17:18:28.286] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 155 eff = 0.997668
[17:18:28.286] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 154 eff = 0.997404
[17:18:28.286] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 174 eff = 0.996899
[17:18:28.286] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 168 eff = 0.996995
[17:18:28.287] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 159 eff = 0.996851
[17:18:28.287] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 161 eff = 0.996995
[17:18:28.287] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 145 eff = 0.998462
[17:18:28.287] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 162 eff = 0.999327
[17:18:28.288] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 184 eff = 0.999207
[17:18:55.632] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[17:18:55.632] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[17:18:55.632] INFO: ----------------------------------------------------------------------
[17:18:55.632] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:18:55.632] INFO: ----------------------------------------------------------------------
[17:18:55.633] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[17:18:55.633] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[17:18:55.633] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[17:18:55.633] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[17:18:55.633] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[17:18:55.633] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[17:18:55.633] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[17:18:55.633] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[17:18:55.633] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[17:18:55.633] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[17:18:55.633] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[17:18:55.633] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[17:18:55.648] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:18:56.316] INFO: Expecting 208000 events.
[17:19:11.262] INFO: 208000 events read in total (14420ms).
[17:19:11.280] INFO: Test took 15632ms.
[17:19:11.890] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:19:11.890] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3549112
[17:19:11.890] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[17:19:11.891] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:19:12.352] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:19:12.352] INFO: number of red-efficiency pixels: 227 145 316 510 596 598 641 493 456 650 565 575 545 384 170 163
[17:19:12.352] INFO: number of X-ray hits detected: 152668 100822 170842 258875 271987 272154 277542 238021 245212 282612 277728 262577 266751 174910 104903 124949
[17:19:12.352] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:19:12.352] INFO: number of Vcal hits detected: 207713 207842 207655 207442 207326 207336 207290 207448 207506 207284 207370 207373 207391 207570 207787 207833
[17:19:12.352] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.7 99.7 99.7 99.8 99.7 99.7 99.7 99.7 99.8 99.9 99.9
[17:19:12.352] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.7 99.7 99.7 99.8 99.7 99.7 99.7 99.7 99.8 99.9 99.9
[17:19:12.352] INFO: X-ray hit rate [MHz/cm2]: 44.7 29.6 50.1 75.9 79.7 79.8 81.3 69.8 71.9 82.8 81.4 77.0 78.2 51.3 30.7 36.6
[17:19:12.352] INFO: PixTestHighRate::doXPixelAlive() done
[17:19:12.397] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[17:19:12.397] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[17:19:12.397] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[17:19:12.397] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[17:19:12.397] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[17:19:12.397] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[17:19:12.397] INFO: PixTest:: pg_setup set to default.
[17:19:36.944] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:19:36.945] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 390.7mA
[17:19:36.946] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[17:19:36.946] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[17:19:36.946] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:19:36.946] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:19:36.946] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:19:36.946] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:19:36.946] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:19:36.946] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:19:36.946] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:19:36.946] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:19:36.946] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:19:36.946] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:19:37.027] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:19:37.027] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:19:37.036] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:19:37.036] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:19:37.036] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:19:37.036] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:19:37.036] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:19:37.037] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:19:37.039] QUIET: Connection to board 58 closed.
[17:19:37.118] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[17:11:37.820] INFO: *** Welcome to pxar ***
[17:11:37.820] INFO: *** Today: 2016/06/02
[17:11:37.843] INFO: *** Version: v1.9.0-805-geb36
[17:11:37.843] INFO: readRocDacs: data/mk317/dacParameters35_C0.dat .. data/mk317/dacParameters35_C15.dat
[17:11:37.844] INFO: readTbmDacs: data/mk317/tbmParameters_C0a.dat .. data/mk317/tbmParameters_C0b.dat
[17:11:37.844] INFO: readMaskFile: data/mk317/defaultMaskFile.dat
[17:11:37.844] INFO: readTrimFile: data/mk317/trimParameters35_C0.dat .. data/mk317/trimParameters35_C15.dat
[17:11:37.995] INFO: clk: 4
[17:11:37.995] INFO: ctr: 4
[17:11:37.995] INFO: sda: 19
[17:11:37.995] INFO: tin: 9
[17:11:37.995] INFO: level: 15
[17:11:37.995] INFO: triggerdelay: 0
[17:11:37.995] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:11:37.995] INFO: Log level: DEBUG
[17:11:38.006] QUIET: Connection to board DTB_WRPRHI opened.
[17:11:38.009] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[17:11:38.012] INFO: RPC call hashes of host and DTB match: 398089610
[17:11:39.611] INFO: DUT info:
[17:11:39.611] INFO: The DUT currently contains the following objects:
[17:11:39.611] INFO: 2 TBM Cores tbm08c (2 ON)
[17:11:39.611] INFO: TBM Core alpha (0): 7 registers set
[17:11:39.611] INFO: TBM Core beta (1): 7 registers set
[17:11:39.611] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:11:39.611] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.611] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.611] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.611] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.611] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.611] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.611] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.611] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.611] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.612] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.612] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.612] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.612] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.612] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.612] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.612] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:11:39.612] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:11:39.613] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:11:39.614] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:11:39.617] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31404032
[17:11:39.617] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2777be0
[17:11:39.617] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2519e60
[17:11:39.617] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f4f49d94010
[17:11:39.617] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f4f4ffff510
[17:11:39.617] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31412224 fPxarMemory = 0x7f4f49d94010
[17:11:39.618] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 387.5mA
[17:11:39.619] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[17:11:39.619] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[17:11:40.109] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2b62930
[17:11:40.168] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:11:40.169] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:11:40.169] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:11:40.174] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:11:40.174] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:11:40.174] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:11:40.183] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:11:40.183] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:11:40.198] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:11:40.198] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:11:40.222] INFO: PixTestCmd::init()
[17:11:40.237] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:11:40.237] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:11:40.237] INFO: readGainPedestalParameters data/mk317/phCalibrationFitErr35_C0.dat .. data/mk317/phCalibrationFitErr35_C15.dat
[17:11:40.478] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:11:40.478] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:11:40.491] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:11:40.497] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:11:40.497] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:11:40.497] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:11:40.521] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:11:40.521] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:11:40.521] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:11:40.521] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:11:40.521] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:11:40.527] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:11:40.527] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:11:40.542] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:11:40.542] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:11:40.542] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:11:40.542] INFO: readReadbackCal: data/mk317/readbackCal_C0.dat .. data/mk317/readbackCal_C15.dat
[17:11:40.554] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:11:40.562] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:11:40.562] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:11:40.562] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:11:40.562] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:11:40.583] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:11:40.583] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:11:46.963] INFO: ######################################################################
[17:11:46.963] INFO: PixTestAlive::doTest()
[17:11:46.963] INFO: ######################################################################
[17:11:46.967] INFO: ----------------------------------------------------------------------
[17:11:46.967] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:11:46.967] INFO: ----------------------------------------------------------------------
[17:11:46.969] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:11:47.347] INFO: Expecting 41600 events.
[17:11:51.597] INFO: 41600 events read in total (3531ms).
[17:11:51.745] INFO: Test took 4776ms.
[17:11:51.754] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:11:51.754] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[17:11:51.754] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[17:11:52.030] INFO: PixTestAlive::aliveTest() done
[17:11:52.030] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:11:52.030] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 56 39 51 75 70 77 89 77 71 94 82 87 71 57 37 39
[17:11:52.051] INFO: ----------------------------------------------------------------------
[17:11:52.051] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:11:52.052] INFO: ----------------------------------------------------------------------
[17:11:52.054] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:11:52.428] INFO: Expecting 41600 events.
[17:11:55.481] INFO: 41600 events read in total (2338ms).
[17:11:55.482] INFO: Test took 3428ms.
[17:11:55.482] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:11:55.482] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[17:11:55.482] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[17:11:55.482] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:11:55.904] INFO: PixTestAlive::maskTest() done
[17:11:55.904] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:11:55.924] INFO: ----------------------------------------------------------------------
[17:11:55.924] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:11:55.924] INFO: ----------------------------------------------------------------------
[17:11:55.926] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:11:56.300] INFO: Expecting 41600 events.
[17:12:00.555] INFO: 41600 events read in total (3540ms).
[17:12:00.556] INFO: Test took 4630ms.
[17:12:00.565] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:00.566] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[17:12:00.566] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[17:12:00.938] INFO: PixTestAlive::addressDecodingTest() done
[17:12:00.938] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:12:00.938] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[17:12:42.744] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[17:12:42.744] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C3_V0 -> PixelAlive_mod
[17:12:46.912] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[17:12:46.912] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[17:12:46.912] INFO: ----------------------------------------------------------------------
[17:12:46.912] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:12:46.912] INFO: ----------------------------------------------------------------------
[17:12:47.051] INFO: Expecting 768 events.
[17:12:48.183] INFO: 768 events read in total (417ms).
[17:12:48.183] INFO: Test took 1265ms.
[17:12:48.190] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:12:48.987] INFO: Expecting 41600 events.
[17:12:52.747] INFO: 41600 events read in total (3233ms).
[17:12:52.752] INFO: Test took 4562ms.
[17:12:52.915] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:52.915] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 956241
[17:12:52.915] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[17:12:52.915] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:12:52.984] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:12:53.649] INFO: Expecting 41600 events.
[17:12:57.503] INFO: 41600 events read in total (3327ms).
[17:12:57.508] INFO: Test took 4524ms.
[17:12:57.669] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:57.669] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 962504
[17:12:57.669] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[17:12:57.670] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:12:57.741] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:12:58.407] INFO: Expecting 41600 events.
[17:13:02.292] INFO: 41600 events read in total (3358ms).
[17:13:02.297] INFO: Test took 4556ms.
[17:13:02.459] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:02.459] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 966794
[17:13:02.460] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[17:13:02.460] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:02.530] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:03.195] INFO: Expecting 41600 events.
[17:13:07.070] INFO: 41600 events read in total (3349ms).
[17:13:07.076] INFO: Test took 4546ms.
[17:13:07.235] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:07.236] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 965813
[17:13:07.236] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[17:13:07.236] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:07.306] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:07.971] INFO: Expecting 41600 events.
[17:13:11.877] INFO: 41600 events read in total (3380ms).
[17:13:11.883] INFO: Test took 4577ms.
[17:13:12.043] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:12.043] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 967927
[17:13:12.043] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[17:13:12.043] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:12.112] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:12.777] INFO: Expecting 41600 events.
[17:13:16.695] INFO: 41600 events read in total (3391ms).
[17:13:16.700] INFO: Test took 4588ms.
[17:13:16.859] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:16.860] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 969085
[17:13:16.860] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[17:13:16.860] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:16.929] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:17.594] INFO: Expecting 41600 events.
[17:13:21.504] INFO: 41600 events read in total (3384ms).
[17:13:21.510] INFO: Test took 4581ms.
[17:13:21.668] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:21.668] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 967342
[17:13:21.668] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[17:13:21.668] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:21.738] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:22.403] INFO: Expecting 41600 events.
[17:13:26.337] INFO: 41600 events read in total (3408ms).
[17:13:26.342] INFO: Test took 4604ms.
[17:13:26.501] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:26.501] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 967790
[17:13:26.501] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[17:13:26.502] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:26.571] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:27.236] INFO: Expecting 41600 events.
[17:13:31.172] INFO: 41600 events read in total (3410ms).
[17:13:31.178] INFO: Test took 4607ms.
[17:13:31.337] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:31.337] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 967030
[17:13:31.337] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[17:13:31.337] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:31.408] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:32.073] INFO: Expecting 41600 events.
[17:13:35.998] INFO: 41600 events read in total (3399ms).
[17:13:36.004] INFO: Test took 4596ms.
[17:13:36.165] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:36.165] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 969244
[17:13:36.165] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[17:13:36.165] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:36.236] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:36.901] INFO: Expecting 41600 events.
[17:13:40.835] INFO: 41600 events read in total (3407ms).
[17:13:40.841] INFO: Test took 4605ms.
[17:13:41.001] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:41.001] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 968161
[17:13:41.001] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[17:13:41.002] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:41.073] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:41.737] INFO: Expecting 41600 events.
[17:13:45.662] INFO: 41600 events read in total (3398ms).
[17:13:45.668] INFO: Test took 4595ms.
[17:13:45.828] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:45.828] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 968073
[17:13:45.828] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[17:13:45.828] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:45.898] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:46.563] INFO: Expecting 41600 events.
[17:13:50.482] INFO: 41600 events read in total (3393ms).
[17:13:50.487] INFO: Test took 4589ms.
[17:13:50.648] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:50.648] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 968390
[17:13:50.648] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[17:13:50.648] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:50.718] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:51.383] INFO: Expecting 41600 events.
[17:13:55.310] INFO: 41600 events read in total (3400ms).
[17:13:55.316] INFO: Test took 4598ms.
[17:13:55.477] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:55.477] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 968713
[17:13:55.477] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[17:13:55.477] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:55.547] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:56.212] INFO: Expecting 41600 events.
[17:14:00.156] INFO: 41600 events read in total (3417ms).
[17:14:00.161] INFO: Test took 4614ms.
[17:14:00.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:14:00.319] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 968209
[17:14:00.320] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[17:14:00.320] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:14:00.390] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:14:01.055] INFO: Expecting 41600 events.
[17:14:04.977] INFO: 41600 events read in total (3395ms).
[17:14:04.983] INFO: Test took 4593ms.
[17:14:05.143] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:14:05.143] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 968312
[17:14:05.143] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[17:14:05.144] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:14:05.214] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:14:05.878] INFO: Expecting 41600 events.
[17:14:09.794] INFO: 41600 events read in total (3389ms).
[17:14:09.800] INFO: Test took 4586ms.
[17:14:09.956] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:14:09.956] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 967513
[17:14:09.956] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[17:14:09.957] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:14:10.027] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:14:10.692] INFO: Expecting 41600 events.
[17:14:14.614] INFO: 41600 events read in total (3395ms).
[17:14:14.620] INFO: Test took 4593ms.
[17:14:14.780] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:14:14.780] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 966616
[17:14:14.780] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[17:14:14.781] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:14:14.851] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:14:15.516] INFO: Expecting 41600 events.
[17:14:19.425] INFO: 41600 events read in total (3382ms).
[17:14:19.430] INFO: Test took 4579ms.
[17:14:19.588] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:14:19.588] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 967078
[17:14:19.588] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[17:14:19.589] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:14:19.658] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:14:20.323] INFO: Expecting 41600 events.
[17:14:24.117] INFO: 41600 events read in total (3268ms).
[17:14:24.122] INFO: Test took 4464ms.
[17:14:24.280] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:14:24.280] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 963361
[17:14:24.280] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[17:14:24.280] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:14:24.573] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 167 eff = 0.998005
[17:14:24.573] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 165 eff = 0.99887
[17:14:24.573] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 149 eff = 0.996755
[17:14:24.574] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 141 eff = 0.995337
[17:14:24.574] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 142 eff = 0.99399
[17:14:24.574] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 166 eff = 0.994351
[17:14:24.574] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 166 eff = 0.994519
[17:14:24.574] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 155 eff = 0.994976
[17:14:24.574] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 154 eff = 0.99601
[17:14:24.574] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 174 eff = 0.994399
[17:14:24.575] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 167 eff = 0.994231
[17:14:24.575] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 159 eff = 0.994231
[17:14:24.575] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 162 eff = 0.995096
[17:14:24.575] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 148 eff = 0.997236
[17:14:24.575] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 150 eff = 0.998822
[17:14:24.576] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 173 eff = 0.998654
[17:15:04.888] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[17:15:04.888] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[17:15:04.888] INFO: ----------------------------------------------------------------------
[17:15:04.888] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:15:04.888] INFO: ----------------------------------------------------------------------
[17:15:04.889] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[17:15:04.889] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[17:15:04.889] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[17:15:04.889] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[17:15:04.889] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[17:15:04.889] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[17:15:04.889] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[17:15:04.889] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[17:15:04.889] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[17:15:04.889] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[17:15:04.889] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[17:15:04.889] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[17:15:04.905] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:15:05.570] INFO: Expecting 208000 events.
[17:15:22.264] INFO: 208000 events read in total (16167ms).
[17:15:22.287] INFO: Test took 17382ms.
[17:15:23.088] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:23.088] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4519749
[17:15:23.088] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[17:15:23.089] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:15:23.606] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:15:23.606] INFO: number of red-efficiency pixels: 309 190 585 807 943 937 1037 828 811 1006 987 896 958 520 275 293
[17:15:23.606] INFO: number of X-ray hits detected: 195167 128575 218523 328692 347939 347762 356528 305168 313908 361314 355951 335307 341326 222351 134156 160523
[17:15:23.606] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:15:23.606] INFO: number of Vcal hits detected: 207619 207802 207265 207055 206864 206900 206770 207020 207076 206812 206808 206944 206832 207396 207701 207687
[17:15:23.606] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.7 99.6 99.5 99.5 99.5 99.6 99.6 99.5 99.5 99.5 99.5 99.7 99.9 99.9
[17:15:23.606] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.5 99.5 99.5 99.4 99.5 99.6 99.4 99.4 99.5 99.4 99.7 99.9 99.8
[17:15:23.606] INFO: X-ray hit rate [MHz/cm2]: 57.2 37.7 64.1 96.3 102.0 101.9 104.5 89.4 92.0 105.9 104.3 98.3 100.0 65.2 39.3 47.1
[17:15:23.606] INFO: PixTestHighRate::doXPixelAlive() done
[17:15:23.652] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[17:15:23.652] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[17:15:23.652] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[17:15:23.652] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[17:15:23.652] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[17:15:23.652] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[17:15:23.652] INFO: PixTest:: pg_setup set to default.
[17:16:14.392] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:16:14.393] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[17:16:14.394] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.1mA
[17:16:14.394] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[17:16:14.394] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:16:14.394] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:16:14.394] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:16:14.394] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:16:14.394] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:16:14.394] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:16:14.394] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:16:14.394] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:16:14.394] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:16:14.394] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:16:14.474] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:16:14.474] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:16:14.488] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:16:14.488] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:16:14.488] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:16:14.488] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:16:14.488] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:16:14.488] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:16:14.490] QUIET: Connection to board 58 closed.
[17:16:14.570] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master