Test Date: 1973-11-29 16:33
Analysis date: 2016-06-14 16:56
Logfile
hrData_40.log
[16:14:21.218] INFO: *** Welcome to pxar ***
[16:14:21.218] INFO: *** Today: 2016/06/13
[16:14:21.243] INFO: *** Version: v1.9.0-814-g7497
[16:14:21.243] INFO: readRocDacs: data/ml335/dacParameters35_C0.dat .. data/ml335/dacParameters35_C15.dat
[16:14:21.244] INFO: readTbmDacs: data/ml335/tbmParameters_C0a.dat .. data/ml335/tbmParameters_C0b.dat
[16:14:21.244] INFO: readMaskFile: data/ml335/defaultMaskFile.dat
[16:14:21.244] INFO: readTrimFile: data/ml335/trimParameters35_C0.dat .. data/ml335/trimParameters35_C15.dat
[16:14:21.395] INFO: clk: 4
[16:14:21.395] INFO: ctr: 4
[16:14:21.395] INFO: sda: 19
[16:14:21.395] INFO: tin: 9
[16:14:21.395] INFO: level: 15
[16:14:21.395] INFO: triggerdelay: 0
[16:14:21.395] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:14:21.395] INFO: Log level: DEBUG
[16:14:21.406] QUIET: Connection to board DTB_WRMNMR opened.
[16:14:21.410] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[16:14:21.413] INFO: RPC call hashes of host and DTB match: 398089610
[16:14:23.014] INFO: DUT info:
[16:14:23.014] INFO: The DUT currently contains the following objects:
[16:14:23.014] INFO: 2 TBM Cores tbm08c (2 ON)
[16:14:23.014] INFO: TBM Core alpha (0): 7 registers set
[16:14:23.014] INFO: TBM Core beta (1): 7 registers set
[16:14:23.014] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:14:23.014] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:14:23.015] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:14:23.015] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:14:23.015] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:14:23.015] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:14:23.015] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:14:23.015] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:14:23.015] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:14:23.015] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:14:23.015] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:14:23.015] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:14:23.015] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:14:23.016] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:14:23.017] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:14:23.018] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:14:23.021] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33112064
[16:14:23.021] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xd100d0
[16:14:23.021] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xab2360
[16:14:23.021] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f6dc9d94010
[16:14:23.021] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f6dcffff510
[16:14:23.021] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33120256 fPxarMemory = 0x7f6dc9d94010
[16:14:23.022] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[16:14:23.023] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 464.7mA
[16:14:23.023] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[16:14:23.516] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x10facf0
[16:14:23.577] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:14:23.577] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:14:23.578] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:14:23.583] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:14:23.583] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:14:23.583] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:14:23.593] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:14:23.594] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:14:23.619] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:14:23.619] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:14:23.635] INFO: PixTestCmd::init()
[16:14:23.648] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:14:23.648] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:14:23.648] INFO: readGainPedestalParameters data/ml335/phCalibrationFitErr35_C0.dat .. data/ml335/phCalibrationFitErr35_C15.dat
[16:14:23.891] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:14:23.891] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:14:23.905] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:14:23.911] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:14:23.911] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:14:23.911] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:14:23.932] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:14:23.932] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:14:23.932] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:14:23.932] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:14:23.932] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:14:23.938] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:14:23.939] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:14:23.954] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:14:23.954] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:14:23.954] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:14:23.954] INFO: readReadbackCal: data/ml335/readbackCal_C0.dat .. data/ml335/readbackCal_C15.dat
[16:14:23.965] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:14:23.975] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:14:23.975] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:14:23.975] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:14:23.975] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:14:23.996] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:14:23.996] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:14:28.541] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[16:14:30.301] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[16:14:31.698] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[16:14:31.698] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[16:14:31.698] INFO: ----------------------------------------------------------------------
[16:14:31.698] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[16:14:31.698] INFO: ----------------------------------------------------------------------
[16:14:32.672] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[16:14:44.248] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[16:14:44.252] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:15:11.575] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1140621 events.
[16:15:18.687] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1140621, pixels seen in all events: 6564690
[16:15:18.783] INFO: Resuming triggers.
[16:15:30.366] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[16:15:30.403] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:15:57.784] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1141067 events.
[16:16:04.924] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1141067, pixels seen in all events: 6558158
[16:16:05.108] INFO: Resuming triggers.
[16:16:16.695] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[16:16:16.732] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:16:43.960] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1141505 events.
[16:16:51.088] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1141505, pixels seen in all events: 6553248
[16:16:51.270] INFO: Resuming triggers.
[16:17:02.861] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[16:17:02.900] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:17:30.169] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1141936 events.
[16:17:37.282] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1141936, pixels seen in all events: 6548180
[16:17:37.466] INFO: Resuming triggers.
[16:17:49.058] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[16:17:49.097] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:18:16.447] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1141960 events.
[16:18:23.467] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1141960, pixels seen in all events: 6547755
[16:18:23.731] INFO: Resuming triggers.
[16:18:25.877] INFO: data taking finished, elapsed time: 60 seconds.
[16:18:26.073] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:18:31.237] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 211423 events.
[16:18:32.549] DEBUG: <PixTestXray.cc/processData:L823> # events read: 211423, pixels seen in all events: 1211951
[16:18:32.617] INFO: PixTest:: pg_setup set to default.
[16:18:32.661] INFO: PixTestXray::doPhRun() done
[16:18:36.802] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:18:36.803] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 382.7mA
[16:18:36.803] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 464.7mA
[16:18:36.804] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[16:18:36.804] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:18:36.804] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:18:36.804] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:18:36.804] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:18:36.804] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:18:36.804] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:18:36.804] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:18:36.804] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:18:36.804] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:18:36.804] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:18:36.804] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:18:36.804] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:18:36.804] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:18:36.804] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:18:36.805] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:18:36.805] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:18:36.805] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:18:36.805] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:18:37.081] QUIET: Connection to board 43 closed.
[16:18:37.160] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[16:18:42.610] INFO: *** Welcome to pxar ***
[16:18:42.610] INFO: *** Today: 2016/06/13
[16:18:42.635] INFO: *** Version: v1.9.0-814-g7497
[16:18:42.635] INFO: readRocDacs: data/ml335/dacParameters35_C0.dat .. data/ml335/dacParameters35_C15.dat
[16:18:42.636] INFO: readTbmDacs: data/ml335/tbmParameters_C0a.dat .. data/ml335/tbmParameters_C0b.dat
[16:18:42.636] INFO: readMaskFile: data/ml335/defaultMaskFile.dat
[16:18:42.636] INFO: readTrimFile: data/ml335/trimParameters35_C0.dat .. data/ml335/trimParameters35_C15.dat
[16:18:42.787] INFO: clk: 4
[16:18:42.787] INFO: ctr: 4
[16:18:42.787] INFO: sda: 19
[16:18:42.787] INFO: tin: 9
[16:18:42.787] INFO: level: 15
[16:18:42.787] INFO: triggerdelay: 0
[16:18:42.787] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:18:42.787] INFO: Log level: DEBUG
[16:18:42.798] QUIET: Connection to board DTB_WRMNMR opened.
[16:18:42.801] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[16:18:42.804] INFO: RPC call hashes of host and DTB match: 398089610
[16:18:44.407] INFO: DUT info:
[16:18:44.407] INFO: The DUT currently contains the following objects:
[16:18:44.407] INFO: 2 TBM Cores tbm08c (2 ON)
[16:18:44.407] INFO: TBM Core alpha (0): 7 registers set
[16:18:44.407] INFO: TBM Core beta (1): 7 registers set
[16:18:44.408] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:18:44.408] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:44.408] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:18:44.408] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:18:44.408] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:18:44.408] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:18:44.408] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:18:44.408] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:18:44.409] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:18:44.410] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:18:44.411] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:18:44.414] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33284096
[16:18:44.414] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xfd30d0
[16:18:44.414] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xd75360
[16:18:44.414] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f017fd83010
[16:18:44.414] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f0185fee510
[16:18:44.414] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33292288 fPxarMemory = 0x7f017fd83010
[16:18:44.415] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[16:18:44.416] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 464.7mA
[16:18:44.416] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:18:44.909] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x13bdc30
[16:18:44.973] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:18:44.973] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:18:44.973] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:18:44.980] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:18:44.980] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:18:44.980] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:18:44.994] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:18:44.994] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:18:45.013] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:18:45.014] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:18:45.026] INFO: PixTestCmd::init()
[16:18:45.040] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:18:45.040] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:18:45.040] INFO: readGainPedestalParameters data/ml335/phCalibrationFitErr35_C0.dat .. data/ml335/phCalibrationFitErr35_C15.dat
[16:18:45.288] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:18:45.288] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:18:45.302] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:18:45.308] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:18:45.308] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:18:45.308] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:18:45.328] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:18:45.328] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:18:45.328] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:18:45.328] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:18:45.328] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:18:45.336] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:18:45.336] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:18:45.355] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:18:45.355] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:18:45.355] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:18:45.355] INFO: readReadbackCal: data/ml335/readbackCal_C0.dat .. data/ml335/readbackCal_C15.dat
[16:18:45.365] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:18:45.373] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:18:45.373] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:18:45.373] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:18:45.373] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:18:45.388] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:18:45.388] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:18:50.845] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[16:18:52.589] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[16:18:54.107] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[16:18:54.107] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[16:18:54.107] INFO: ----------------------------------------------------------------------
[16:18:54.107] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[16:18:54.107] INFO: ----------------------------------------------------------------------
[16:18:55.083] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[16:19:02.356] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[16:19:02.360] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:19:27.274] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 716697 events.
[16:19:39.712] DEBUG: <PixTestXray.cc/processData:L823> # events read: 716697, pixels seen in all events: 11652584
[16:19:39.934] INFO: Resuming triggers.
[16:19:47.215] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[16:19:47.249] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:20:12.164] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 717238 events.
[16:20:24.618] DEBUG: <PixTestXray.cc/processData:L823> # events read: 717238, pixels seen in all events: 11645569
[16:20:24.801] INFO: Resuming triggers.
[16:20:32.086] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[16:20:32.128] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:20:57.014] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 717668 events.
[16:21:09.577] DEBUG: <PixTestXray.cc/processData:L823> # events read: 717668, pixels seen in all events: 11639946
[16:21:09.759] INFO: Resuming triggers.
[16:21:17.047] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[16:21:17.100] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:21:42.080] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 717940 events.
[16:21:54.600] DEBUG: <PixTestXray.cc/processData:L823> # events read: 717940, pixels seen in all events: 11637110
[16:21:54.785] INFO: Resuming triggers.
[16:22:02.072] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[16:22:02.125] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:22:27.020] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 717831 events.
[16:22:39.542] DEBUG: <PixTestXray.cc/processData:L823> # events read: 717831, pixels seen in all events: 11638232
[16:22:39.745] INFO: Resuming triggers.
[16:22:47.036] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[16:22:47.075] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:23:12.031] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 718217 events.
[16:23:24.493] DEBUG: <PixTestXray.cc/processData:L823> # events read: 718217, pixels seen in all events: 11632898
[16:23:24.695] INFO: Resuming triggers.
[16:23:31.990] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[16:23:32.027] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:23:56.915] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 718551 events.
[16:24:09.377] DEBUG: <PixTestXray.cc/processData:L823> # events read: 718551, pixels seen in all events: 11628923
[16:24:09.563] INFO: Resuming triggers.
[16:24:16.856] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[16:24:16.907] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:24:41.795] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 718458 events.
[16:24:54.263] DEBUG: <PixTestXray.cc/processData:L823> # events read: 718458, pixels seen in all events: 11630443
[16:24:54.470] INFO: Resuming triggers.
[16:24:56.256] INFO: data taking finished, elapsed time: 60 seconds.
[16:24:56.453] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:25:02.680] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 175967 events.
[16:25:05.723] DEBUG: <PixTestXray.cc/processData:L823> # events read: 175967, pixels seen in all events: 2848106
[16:25:05.784] INFO: PixTest:: pg_setup set to default.
[16:25:05.821] INFO: PixTestXray::doPhRun() done
[16:28:15.186] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:28:15.187] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 382.7mA
[16:28:15.188] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 464.7mA
[16:28:15.188] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:28:15.188] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:28:15.188] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:28:15.188] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:28:15.188] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:28:15.188] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:28:15.188] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:28:15.188] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:28:15.188] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:28:15.188] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:28:15.188] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:28:15.188] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:28:15.189] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:28:15.189] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:28:15.189] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:28:15.189] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:28:15.189] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:28:15.189] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:28:15.189] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:28:15.507] QUIET: Connection to board 43 closed.
[16:28:15.587] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[16:08:39.822] INFO: *** Welcome to pxar ***
[16:08:39.822] INFO: *** Today: 2016/06/13
[16:08:39.847] INFO: *** Version: v1.9.0-814-g7497
[16:08:39.847] INFO: readRocDacs: data/ml335/dacParameters35_C0.dat .. data/ml335/dacParameters35_C15.dat
[16:08:39.848] INFO: readTbmDacs: data/ml335/tbmParameters_C0a.dat .. data/ml335/tbmParameters_C0b.dat
[16:08:39.848] INFO: readMaskFile: data/ml335/defaultMaskFile.dat
[16:08:39.848] INFO: readTrimFile: data/ml335/trimParameters35_C0.dat .. data/ml335/trimParameters35_C15.dat
[16:08:39.997] INFO: clk: 4
[16:08:39.997] INFO: ctr: 4
[16:08:39.997] INFO: sda: 19
[16:08:39.997] INFO: tin: 9
[16:08:39.997] INFO: level: 15
[16:08:39.997] INFO: triggerdelay: 0
[16:08:39.997] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:08:39.997] INFO: Log level: DEBUG
[16:08:40.008] QUIET: Connection to board DTB_WRMNMR opened.
[16:08:40.011] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[16:08:40.014] INFO: RPC call hashes of host and DTB match: 398089610
[16:08:41.615] INFO: DUT info:
[16:08:41.615] INFO: The DUT currently contains the following objects:
[16:08:41.615] INFO: 2 TBM Cores tbm08c (2 ON)
[16:08:41.615] INFO: TBM Core alpha (0): 7 registers set
[16:08:41.615] INFO: TBM Core beta (1): 7 registers set
[16:08:41.615] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:08:41.615] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.615] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.615] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.615] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.615] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.615] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.615] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.615] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.615] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.615] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.615] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.615] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.615] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.615] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.615] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.615] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:08:41.616] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:08:41.617] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:08:41.618] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:08:41.620] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33054720
[16:08:41.620] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1bcf0d0
[16:08:41.621] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1971360
[16:08:41.621] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f0621d94010
[16:08:41.621] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f0627fff510
[16:08:41.621] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33062912 fPxarMemory = 0x7f0621d94010
[16:08:41.622] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[16:08:41.623] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 464.7mA
[16:08:41.623] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[16:08:42.117] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1fb9e10
[16:08:42.181] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:08:42.181] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:08:42.181] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:08:42.187] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:08:42.187] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:08:42.187] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:08:42.197] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:08:42.197] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:08:42.221] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:08:42.221] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:08:42.234] INFO: PixTestCmd::init()
[16:08:42.239] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:08:42.239] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:08:42.239] INFO: readGainPedestalParameters data/ml335/phCalibrationFitErr35_C0.dat .. data/ml335/phCalibrationFitErr35_C15.dat
[16:08:42.481] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:08:42.481] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:08:42.496] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:08:42.502] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:08:42.502] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:08:42.502] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:08:42.521] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:08:42.521] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:08:42.521] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:08:42.521] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:08:42.521] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:08:42.528] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:08:42.528] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:08:42.542] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:08:42.542] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:08:42.542] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:08:42.542] INFO: readReadbackCal: data/ml335/readbackCal_C0.dat .. data/ml335/readbackCal_C15.dat
[16:08:42.552] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:08:42.560] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:08:42.560] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:08:42.560] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:08:42.560] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:08:42.571] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:08:42.572] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:08:45.837] INFO: ######################################################################
[16:08:45.837] INFO: PixTestAlive::doTest()
[16:08:45.837] INFO: ######################################################################
[16:08:45.840] INFO: ----------------------------------------------------------------------
[16:08:45.840] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:08:45.841] INFO: ----------------------------------------------------------------------
[16:08:45.843] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:08:46.222] INFO: Expecting 41600 events.
[16:08:50.449] INFO: 41600 events read in total (3509ms).
[16:08:50.596] INFO: Test took 4753ms.
[16:08:50.606] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:50.606] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:08:50.606] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:08:50.876] INFO: PixTestAlive::aliveTest() done
[16:08:50.876] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:08:50.876] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 17 10 26 36 47 40 46 53 55 41 51 40 43 29 20 30
[16:08:50.901] INFO: ----------------------------------------------------------------------
[16:08:50.902] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:08:50.902] INFO: ----------------------------------------------------------------------
[16:08:50.904] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:08:51.281] INFO: Expecting 41600 events.
[16:08:54.220] INFO: 41600 events read in total (2224ms).
[16:08:54.220] INFO: Test took 3316ms.
[16:08:54.220] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:54.220] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:08:54.220] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:08:54.221] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:08:54.643] INFO: PixTestAlive::maskTest() done
[16:08:54.643] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:08:54.665] INFO: ----------------------------------------------------------------------
[16:08:54.665] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:08:54.665] INFO: ----------------------------------------------------------------------
[16:08:54.667] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:08:55.044] INFO: Expecting 41600 events.
[16:08:59.253] INFO: 41600 events read in total (3494ms).
[16:08:59.255] INFO: Test took 4588ms.
[16:08:59.266] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:59.266] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:08:59.266] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:08:59.634] INFO: PixTestAlive::addressDecodingTest() done
[16:08:59.634] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:08:59.634] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[16:09:29.946] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:09:29.946] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:09:29.946] INFO: ----------------------------------------------------------------------
[16:09:29.946] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:09:29.946] INFO: ----------------------------------------------------------------------
[16:09:30.085] INFO: Expecting 768 events.
[16:09:31.214] INFO: 768 events read in total (414ms).
[16:09:31.215] INFO: Test took 1263ms.
[16:09:31.220] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:32.018] INFO: Expecting 41600 events.
[16:09:35.281] INFO: 41600 events read in total (2737ms).
[16:09:35.285] INFO: Test took 4065ms.
[16:09:35.377] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:35.377] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 523342
[16:09:35.377] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:09:35.377] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:09:35.418] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:36.084] INFO: Expecting 41600 events.
[16:09:39.465] INFO: 41600 events read in total (2855ms).
[16:09:39.468] INFO: Test took 4050ms.
[16:09:39.562] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:39.562] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 529725
[16:09:39.562] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:09:39.562] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:09:39.604] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:40.269] INFO: Expecting 41600 events.
[16:09:43.669] INFO: 41600 events read in total (2873ms).
[16:09:43.675] INFO: Test took 4071ms.
[16:09:43.768] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:43.768] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 532233
[16:09:43.768] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:09:43.768] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:09:43.812] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:44.477] INFO: Expecting 41600 events.
[16:09:47.893] INFO: 41600 events read in total (2890ms).
[16:09:47.899] INFO: Test took 4087ms.
[16:09:47.992] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:47.992] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 531775
[16:09:47.992] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:09:47.993] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:09:48.035] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:48.700] INFO: Expecting 41600 events.
[16:09:52.116] INFO: 41600 events read in total (2889ms).
[16:09:52.122] INFO: Test took 4086ms.
[16:09:52.215] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:52.215] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 531170
[16:09:52.215] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:09:52.216] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:09:52.259] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:52.924] INFO: Expecting 41600 events.
[16:09:56.379] INFO: 41600 events read in total (2928ms).
[16:09:56.383] INFO: Test took 4124ms.
[16:09:56.474] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:56.474] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 531556
[16:09:56.474] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:09:56.474] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:09:56.517] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:09:57.182] INFO: Expecting 41600 events.
[16:10:00.613] INFO: 41600 events read in total (2904ms).
[16:10:00.617] INFO: Test took 4100ms.
[16:10:00.710] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:00.710] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 532693
[16:10:00.710] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:10:00.711] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:10:00.754] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:10:01.418] INFO: Expecting 41600 events.
[16:10:04.846] INFO: 41600 events read in total (2901ms).
[16:10:04.850] INFO: Test took 4096ms.
[16:10:04.941] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:04.941] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 531822
[16:10:04.941] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:10:04.941] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:10:04.985] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:10:05.650] INFO: Expecting 41600 events.
[16:10:09.084] INFO: 41600 events read in total (2907ms).
[16:10:09.088] INFO: Test took 4103ms.
[16:10:09.180] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:09.180] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 531562
[16:10:09.180] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:10:09.180] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:10:09.223] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:10:09.888] INFO: Expecting 41600 events.
[16:10:13.328] INFO: 41600 events read in total (2913ms).
[16:10:13.332] INFO: Test took 4109ms.
[16:10:13.422] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:13.423] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 532807
[16:10:13.423] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:10:13.423] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:10:13.466] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:10:14.131] INFO: Expecting 41600 events.
[16:10:17.561] INFO: 41600 events read in total (2903ms).
[16:10:17.565] INFO: Test took 4099ms.
[16:10:17.656] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:17.656] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 532809
[16:10:17.656] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:10:17.656] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:10:17.700] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:10:18.365] INFO: Expecting 41600 events.
[16:10:21.804] INFO: 41600 events read in total (2913ms).
[16:10:21.808] INFO: Test took 4108ms.
[16:10:21.900] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:21.900] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 531385
[16:10:21.900] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:10:21.900] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:10:21.943] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:10:22.608] INFO: Expecting 41600 events.
[16:10:26.042] INFO: 41600 events read in total (2907ms).
[16:10:26.046] INFO: Test took 4102ms.
[16:10:26.138] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:26.138] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 532556
[16:10:26.138] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:10:26.138] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:10:26.181] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:10:26.847] INFO: Expecting 41600 events.
[16:10:30.276] INFO: 41600 events read in total (2903ms).
[16:10:30.280] INFO: Test took 4098ms.
[16:10:30.372] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:30.372] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 532097
[16:10:30.372] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:10:30.372] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:10:30.416] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:10:31.081] INFO: Expecting 41600 events.
[16:10:34.523] INFO: 41600 events read in total (2915ms).
[16:10:34.526] INFO: Test took 4111ms.
[16:10:34.620] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:34.620] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 533156
[16:10:34.620] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:10:34.620] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:10:34.664] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:10:35.329] INFO: Expecting 41600 events.
[16:10:38.781] INFO: 41600 events read in total (2925ms).
[16:10:38.785] INFO: Test took 4121ms.
[16:10:38.876] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:38.876] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 532985
[16:10:38.876] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:10:38.876] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:10:38.920] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:10:39.585] INFO: Expecting 41600 events.
[16:10:43.023] INFO: 41600 events read in total (2912ms).
[16:10:43.027] INFO: Test took 4107ms.
[16:10:43.119] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:43.119] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 533436
[16:10:43.119] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:10:43.119] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:10:43.163] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:10:43.828] INFO: Expecting 41600 events.
[16:10:47.264] INFO: 41600 events read in total (2910ms).
[16:10:47.268] INFO: Test took 4105ms.
[16:10:47.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:47.360] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 530437
[16:10:47.360] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:10:47.360] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:10:47.404] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:10:48.069] INFO: Expecting 41600 events.
[16:10:51.467] INFO: 41600 events read in total (2872ms).
[16:10:51.473] INFO: Test took 4069ms.
[16:10:51.565] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:51.565] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 530525
[16:10:51.565] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:10:51.565] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:10:51.609] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:10:52.274] INFO: Expecting 41600 events.
[16:10:55.534] INFO: 41600 events read in total (2733ms).
[16:10:55.538] INFO: Test took 3929ms.
[16:10:55.624] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:55.624] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 524198
[16:10:55.624] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:10:55.624] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:10:55.887] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 158 eff = 0.999639
[16:10:55.887] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 177 eff = 0.999808
[16:10:55.887] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 172 eff = 0.999495
[16:10:55.887] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 180 eff = 0.998894
[16:10:55.887] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 183 eff = 0.99875
[16:10:55.888] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 168 eff = 0.998654
[16:10:55.888] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 172 eff = 0.99863
[16:10:55.888] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 171 eff = 0.998702
[16:10:55.888] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 142 eff = 0.998654
[16:10:55.889] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 168 eff = 0.998462
[16:10:55.889] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 158 eff = 0.998798
[16:10:55.889] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 140 eff = 0.998774
[16:10:55.889] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 170 eff = 0.998894
[16:10:55.890] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 147 eff = 0.999279
[16:10:55.890] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 154 eff = 0.999567
[16:10:55.890] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 169 eff = 0.999591
[16:11:00.010] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:11:00.010] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:11:00.010] INFO: ----------------------------------------------------------------------
[16:11:00.010] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:11:00.010] INFO: ----------------------------------------------------------------------
[16:11:00.010] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:11:00.010] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:11:00.010] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:11:00.011] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:11:00.011] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:11:00.011] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:11:00.011] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:11:00.011] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:11:00.011] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:11:00.011] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:11:00.011] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:11:00.011] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:11:00.028] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:11:00.701] INFO: Expecting 208000 events.
[16:11:14.522] INFO: 208000 events read in total (13294ms).
[16:11:14.536] INFO: Test took 14508ms.
[16:11:15.013] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:11:15.013] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2376187
[16:11:15.013] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:11:15.013] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:11:15.406] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:11:15.406] INFO: number of red-efficiency pixels: 122 82 159 220 265 245 281 251 349 282 286 339 302 163 121 108
[16:11:15.406] INFO: number of X-ray hits detected: 89050 68046 112302 167613 179001 178105 177067 167512 181855 188211 185501 172728 178307 114914 69055 80360
[16:11:15.406] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:11:15.406] INFO: number of Vcal hits detected: 207876 207918 207839 207757 207724 207744 207710 207743 207635 207705 207696 207637 207680 207828 207874 207890
[16:11:15.406] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.8 99.9 99.9 99.9 99.9
[16:11:15.406] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9
[16:11:15.406] INFO: X-ray hit rate [MHz/cm2]: 26.1 19.9 32.9 49.1 52.5 52.2 51.9 49.1 53.3 55.2 54.4 50.6 52.3 33.7 20.2 23.6
[16:11:15.406] INFO: PixTestHighRate::doXPixelAlive() done
[16:11:15.451] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:11:15.451] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:11:15.451] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:11:15.451] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:11:15.451] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:11:15.451] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:11:15.451] INFO: PixTest:: pg_setup set to default.
[16:11:28.850] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:11:28.850] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[16:11:30.202] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:11:30.203] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 381.9mA
[16:11:30.203] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 464.7mA
[16:11:30.203] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[16:11:30.203] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:11:30.204] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:11:30.204] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:11:30.204] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:11:30.204] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:11:30.204] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:11:30.204] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:11:30.204] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:11:30.204] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:11:30.204] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:11:30.293] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:11:30.293] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:11:30.302] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:11:30.302] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:11:30.302] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:11:30.302] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:11:30.302] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:11:30.303] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:11:30.306] QUIET: Connection to board 43 closed.
[16:11:30.386] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[16:05:39.388] INFO: *** Welcome to pxar ***
[16:05:39.388] INFO: *** Today: 2016/06/13
[16:05:39.414] INFO: *** Version: v1.9.0-814-g7497
[16:05:39.414] INFO: readRocDacs: data/ml335/dacParameters35_C0.dat .. data/ml335/dacParameters35_C15.dat
[16:05:39.415] INFO: readTbmDacs: data/ml335/tbmParameters_C0a.dat .. data/ml335/tbmParameters_C0b.dat
[16:05:39.415] INFO: readMaskFile: data/ml335/defaultMaskFile.dat
[16:05:39.415] INFO: readTrimFile: data/ml335/trimParameters35_C0.dat .. data/ml335/trimParameters35_C15.dat
[16:05:39.568] INFO: clk: 4
[16:05:39.568] INFO: ctr: 4
[16:05:39.568] INFO: sda: 19
[16:05:39.568] INFO: tin: 9
[16:05:39.568] INFO: level: 15
[16:05:39.568] INFO: triggerdelay: 0
[16:05:39.568] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:05:39.568] INFO: Log level: DEBUG
[16:05:39.579] QUIET: Connection to board DTB_WRMNMR opened.
[16:05:39.583] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[16:05:39.585] INFO: RPC call hashes of host and DTB match: 398089610
[16:05:41.189] INFO: DUT info:
[16:05:41.189] INFO: The DUT currently contains the following objects:
[16:05:41.189] INFO: 2 TBM Cores tbm08c (2 ON)
[16:05:41.189] INFO: TBM Core alpha (0): 7 registers set
[16:05:41.189] INFO: TBM Core beta (1): 7 registers set
[16:05:41.189] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:05:41.189] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.189] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.189] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.189] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.189] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.189] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.189] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.189] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.189] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.189] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.189] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.189] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.189] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.189] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.189] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.189] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:05:41.190] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:05:41.191] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:05:41.192] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:05:41.193] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:05:41.195] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35352576
[16:05:41.195] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xe4e0d0
[16:05:41.195] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xbf0360
[16:05:41.195] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f5e71d94010
[16:05:41.195] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f5e77fff510
[16:05:41.195] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35360768 fPxarMemory = 0x7f5e71d94010
[16:05:41.196] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[16:05:41.198] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 464.7mA
[16:05:41.198] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:05:41.693] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1238db0
[16:05:41.754] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:05:41.754] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:05:41.755] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:05:41.760] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:05:41.760] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:05:41.760] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:05:41.770] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:05:41.770] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:05:41.785] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:05:41.785] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:05:41.798] INFO: PixTestCmd::init()
[16:05:41.810] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:05:41.810] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:05:41.810] INFO: readGainPedestalParameters data/ml335/phCalibrationFitErr35_C0.dat .. data/ml335/phCalibrationFitErr35_C15.dat
[16:05:42.062] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:05:42.063] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:05:42.077] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:05:42.083] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:05:42.083] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:05:42.083] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:05:42.103] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:05:42.103] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:05:42.103] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:05:42.103] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:05:42.103] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:05:42.110] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:05:42.110] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:05:42.126] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:05:42.126] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:05:42.126] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:05:42.126] INFO: readReadbackCal: data/ml335/readbackCal_C0.dat .. data/ml335/readbackCal_C15.dat
[16:05:42.136] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:05:42.144] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:05:42.144] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:05:42.144] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:05:42.144] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:05:42.159] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:05:42.159] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:05:45.573] INFO: ######################################################################
[16:05:45.573] INFO: PixTestAlive::doTest()
[16:05:45.573] INFO: ######################################################################
[16:05:45.576] INFO: ----------------------------------------------------------------------
[16:05:45.577] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:05:45.577] INFO: ----------------------------------------------------------------------
[16:05:45.579] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:45.959] INFO: Expecting 41600 events.
[16:05:50.180] INFO: 41600 events read in total (3503ms).
[16:05:50.325] INFO: Test took 4746ms.
[16:05:50.335] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:50.335] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:05:50.335] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:05:50.606] INFO: PixTestAlive::aliveTest() done
[16:05:50.606] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:05:50.606] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 38 31 50 72 64 75 57 65 75 68 66 64 63 57 20 32
[16:05:50.631] INFO: ----------------------------------------------------------------------
[16:05:50.631] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:05:50.631] INFO: ----------------------------------------------------------------------
[16:05:50.633] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:51.011] INFO: Expecting 41600 events.
[16:05:53.935] INFO: 41600 events read in total (2209ms).
[16:05:53.936] INFO: Test took 3303ms.
[16:05:53.936] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:53.936] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:05:53.936] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:05:53.937] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:05:54.358] INFO: PixTestAlive::maskTest() done
[16:05:54.358] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:05:54.381] INFO: ----------------------------------------------------------------------
[16:05:54.381] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:05:54.381] INFO: ----------------------------------------------------------------------
[16:05:54.383] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:54.761] INFO: Expecting 41600 events.
[16:05:58.946] INFO: 41600 events read in total (3470ms).
[16:05:58.947] INFO: Test took 4564ms.
[16:05:58.959] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:58.959] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:05:58.959] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:05:59.329] INFO: PixTestAlive::addressDecodingTest() done
[16:05:59.329] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:05:59.330] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[16:06:01.482] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:06:01.482] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:06:01.482] INFO: ----------------------------------------------------------------------
[16:06:01.482] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:06:01.482] INFO: ----------------------------------------------------------------------
[16:06:01.621] INFO: Expecting 768 events.
[16:06:02.750] INFO: 768 events read in total (414ms).
[16:06:02.751] INFO: Test took 1263ms.
[16:06:02.757] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:03.554] INFO: Expecting 41600 events.
[16:06:07.128] INFO: 41600 events read in total (3047ms).
[16:06:07.133] INFO: Test took 4376ms.
[16:06:07.272] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:07.272] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 802393
[16:06:07.272] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:06:07.272] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:07.332] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:07.997] INFO: Expecting 41600 events.
[16:06:11.684] INFO: 41600 events read in total (3160ms).
[16:06:11.689] INFO: Test took 4357ms.
[16:06:11.823] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:11.823] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 811279
[16:06:11.823] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:06:11.823] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:11.885] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:12.550] INFO: Expecting 41600 events.
[16:06:16.263] INFO: 41600 events read in total (3187ms).
[16:06:16.268] INFO: Test took 4383ms.
[16:06:16.399] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:16.400] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 811778
[16:06:16.400] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:06:16.400] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:16.462] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:17.127] INFO: Expecting 41600 events.
[16:06:20.865] INFO: 41600 events read in total (3211ms).
[16:06:20.870] INFO: Test took 4408ms.
[16:06:21.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:21.002] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 811716
[16:06:21.002] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:06:21.003] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:21.066] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:21.731] INFO: Expecting 41600 events.
[16:06:25.449] INFO: 41600 events read in total (3191ms).
[16:06:25.454] INFO: Test took 4388ms.
[16:06:25.587] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:25.587] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 811863
[16:06:25.587] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:06:25.587] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:25.649] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:26.314] INFO: Expecting 41600 events.
[16:06:30.061] INFO: 41600 events read in total (3220ms).
[16:06:30.066] INFO: Test took 4417ms.
[16:06:30.199] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:30.199] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 812359
[16:06:30.199] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:06:30.199] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:30.261] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:30.926] INFO: Expecting 41600 events.
[16:06:34.666] INFO: 41600 events read in total (3213ms).
[16:06:34.671] INFO: Test took 4410ms.
[16:06:34.804] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:34.804] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 815387
[16:06:34.804] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:06:34.805] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:34.867] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:35.532] INFO: Expecting 41600 events.
[16:06:39.305] INFO: 41600 events read in total (3246ms).
[16:06:39.310] INFO: Test took 4444ms.
[16:06:39.446] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:39.446] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 815030
[16:06:39.446] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:06:39.446] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:39.508] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:40.172] INFO: Expecting 41600 events.
[16:06:43.928] INFO: 41600 events read in total (3229ms).
[16:06:43.933] INFO: Test took 4425ms.
[16:06:44.070] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:44.070] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 814404
[16:06:44.070] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:06:44.070] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:44.132] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:44.797] INFO: Expecting 41600 events.
[16:06:48.561] INFO: 41600 events read in total (3238ms).
[16:06:48.566] INFO: Test took 4434ms.
[16:06:48.701] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:48.701] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 814397
[16:06:48.701] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:06:48.701] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:48.763] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:49.428] INFO: Expecting 41600 events.
[16:06:53.187] INFO: 41600 events read in total (3232ms).
[16:06:53.192] INFO: Test took 4429ms.
[16:06:53.326] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:53.326] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 814863
[16:06:53.326] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:06:53.326] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:53.389] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:54.055] INFO: Expecting 41600 events.
[16:06:57.817] INFO: 41600 events read in total (3236ms).
[16:06:57.822] INFO: Test took 4433ms.
[16:06:57.955] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:06:57.955] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 815188
[16:06:57.955] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:06:57.955] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:06:58.019] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:06:58.684] INFO: Expecting 41600 events.
[16:07:02.447] INFO: 41600 events read in total (3236ms).
[16:07:02.452] INFO: Test took 4433ms.
[16:07:02.585] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:02.585] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 813563
[16:07:02.585] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:07:02.585] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:07:02.648] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:07:03.312] INFO: Expecting 41600 events.
[16:07:07.068] INFO: 41600 events read in total (3229ms).
[16:07:07.073] INFO: Test took 4425ms.
[16:07:07.210] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:07.210] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 814290
[16:07:07.210] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:07:07.210] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:07:07.272] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:07:07.937] INFO: Expecting 41600 events.
[16:07:11.677] INFO: 41600 events read in total (3213ms).
[16:07:11.683] INFO: Test took 4411ms.
[16:07:11.815] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:11.815] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 812667
[16:07:11.815] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:07:11.816] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:07:11.879] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:07:12.544] INFO: Expecting 41600 events.
[16:07:16.298] INFO: 41600 events read in total (3227ms).
[16:07:16.303] INFO: Test took 4424ms.
[16:07:16.437] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:16.437] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 812353
[16:07:16.437] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:07:16.437] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:07:16.500] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:07:17.165] INFO: Expecting 41600 events.
[16:07:20.926] INFO: 41600 events read in total (3235ms).
[16:07:20.931] INFO: Test took 4431ms.
[16:07:21.065] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:21.065] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 812402
[16:07:21.065] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:07:21.065] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:07:21.127] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:07:21.793] INFO: Expecting 41600 events.
[16:07:25.529] INFO: 41600 events read in total (3210ms).
[16:07:25.534] INFO: Test took 4407ms.
[16:07:25.668] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:25.668] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 813067
[16:07:25.668] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:07:25.669] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:07:25.731] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:07:26.397] INFO: Expecting 41600 events.
[16:07:30.128] INFO: 41600 events read in total (3205ms).
[16:07:30.133] INFO: Test took 4402ms.
[16:07:30.267] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:30.267] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 813756
[16:07:30.267] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:07:30.267] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:07:30.330] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:07:30.995] INFO: Expecting 41600 events.
[16:07:34.557] INFO: 41600 events read in total (3035ms).
[16:07:34.562] INFO: Test took 4232ms.
[16:07:34.695] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:34.695] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 806556
[16:07:34.695] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:07:34.695] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:07:34.979] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 161 eff = 0.999159
[16:07:34.979] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 181 eff = 0.999255
[16:07:34.979] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 172 eff = 0.99851
[16:07:34.979] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 177 eff = 0.997043
[16:07:34.979] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 181 eff = 0.996538
[16:07:34.979] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 169 eff = 0.996923
[16:07:34.980] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 173 eff = 0.996659
[16:07:34.980] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 173 eff = 0.997139
[16:07:34.980] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 156 eff = 0.996995
[16:07:34.980] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 170 eff = 0.996394
[16:07:34.980] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 157 eff = 0.996635
[16:07:34.980] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 159 eff = 0.996466
[16:07:34.980] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 171 eff = 0.996659
[16:07:34.981] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 152 eff = 0.998365
[16:07:34.981] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 174 eff = 0.999327
[16:07:34.981] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 166 eff = 0.999183
[16:07:38.546] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:07:38.546] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:07:38.546] INFO: ----------------------------------------------------------------------
[16:07:38.546] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:07:38.546] INFO: ----------------------------------------------------------------------
[16:07:38.546] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:07:38.546] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:07:38.546] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:07:38.546] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:07:38.547] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:07:38.547] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:07:38.547] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:07:38.547] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:07:38.547] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:07:38.547] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:07:38.547] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:07:38.547] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:07:38.565] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:07:39.233] INFO: Expecting 208000 events.
[16:07:55.294] INFO: 208000 events read in total (15534ms).
[16:07:55.314] INFO: Test took 16749ms.
[16:07:55.969] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:55.969] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3760065
[16:07:55.969] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:07:55.970] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:07:56.448] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:07:56.448] INFO: number of red-efficiency pixels: 244 147 328 541 648 611 617 570 606 735 644 616 645 324 162 193
[16:07:56.448] INFO: number of X-ray hits detected: 143309 108110 178369 267724 287321 284458 283921 266993 291185 300212 295181 276316 287191 183987 109702 129526
[16:07:56.449] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:07:56.449] INFO: number of Vcal hits detected: 207743 207827 207644 207408 207267 207317 207324 207357 207338 207148 207267 207310 207280 207647 207831 207799
[16:07:56.449] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.7 99.7 99.7 99.7 99.6 99.7 99.7 99.7 99.8 99.9 99.9
[16:07:56.449] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.7 99.7 99.7 99.7 99.6 99.6 99.7 99.7 99.8 99.9 99.9
[16:07:56.449] INFO: X-ray hit rate [MHz/cm2]: 42.0 31.7 52.3 78.5 84.2 83.4 83.2 78.3 85.3 88.0 86.5 81.0 84.2 53.9 32.2 38.0
[16:07:56.449] INFO: PixTestHighRate::doXPixelAlive() done
[16:07:56.494] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:07:56.494] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:07:56.494] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:07:56.494] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:07:56.494] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:07:56.494] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:07:56.494] INFO: PixTest:: pg_setup set to default.
[16:08:33.362] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:08:33.362] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[16:08:34.930] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:08:34.931] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 382.7mA
[16:08:34.931] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 464.7mA
[16:08:34.931] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: 0.1 C
[16:08:34.931] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:08:34.932] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:08:34.932] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:08:34.932] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:08:34.932] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:08:34.932] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:08:34.932] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:08:34.932] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:08:34.932] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:08:34.932] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:08:35.024] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:08:35.024] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:08:35.033] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:08:35.033] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:08:35.033] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:08:35.033] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:08:35.034] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:08:35.034] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:08:35.036] QUIET: Connection to board 43 closed.
[16:08:35.115] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[16:02:59.984] INFO: *** Welcome to pxar ***
[16:02:59.984] INFO: *** Today: 2016/06/13
[16:03:00.012] INFO: *** Version: v1.9.0-814-g7497
[16:03:00.012] INFO: readRocDacs: data/ml335/dacParameters35_C0.dat .. data/ml335/dacParameters35_C15.dat
[16:03:00.013] INFO: readTbmDacs: data/ml335/tbmParameters_C0a.dat .. data/ml335/tbmParameters_C0b.dat
[16:03:00.014] INFO: readMaskFile: data/ml335/defaultMaskFile.dat
[16:03:00.014] INFO: readTrimFile: data/ml335/trimParameters35_C0.dat .. data/ml335/trimParameters35_C15.dat
[16:03:00.165] INFO: clk: 4
[16:03:00.166] INFO: ctr: 4
[16:03:00.166] INFO: sda: 19
[16:03:00.166] INFO: tin: 9
[16:03:00.166] INFO: level: 15
[16:03:00.166] INFO: triggerdelay: 0
[16:03:00.166] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:03:00.166] INFO: Log level: DEBUG
[16:03:00.177] QUIET: Connection to board DTB_WRMNMR opened.
[16:03:00.181] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[16:03:00.184] INFO: RPC call hashes of host and DTB match: 398089610
[16:03:01.787] INFO: DUT info:
[16:03:01.787] INFO: The DUT currently contains the following objects:
[16:03:01.787] INFO: 2 TBM Cores tbm08c (2 ON)
[16:03:01.787] INFO: TBM Core alpha (0): 7 registers set
[16:03:01.787] INFO: TBM Core beta (1): 7 registers set
[16:03:01.787] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:03:01.787] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.787] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.787] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.787] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.787] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.787] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.787] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.787] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.787] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.787] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.787] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.788] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.788] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.788] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.788] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.788] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:03:01.788] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:03:01.789] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:03:01.790] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:03:01.791] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:03:01.793] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35581952
[16:03:01.793] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x11190d0
[16:03:01.793] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xebb360
[16:03:01.793] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fddf1d94010
[16:03:01.793] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fddf7fff510
[16:03:01.793] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35590144 fPxarMemory = 0x7fddf1d94010
[16:03:01.795] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[16:03:01.796] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 464.7mA
[16:03:01.796] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:03:02.292] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1503d40
[16:03:02.357] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:03:02.357] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:03:02.357] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:03:02.363] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:03:02.363] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:03:02.363] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:03:02.375] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:03:02.375] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:03:02.393] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:03:02.393] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:03:02.400] INFO: PixTestCmd::init()
[16:03:02.406] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:03:02.406] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:03:02.406] INFO: readGainPedestalParameters data/ml335/phCalibrationFitErr35_C0.dat .. data/ml335/phCalibrationFitErr35_C15.dat
[16:03:02.650] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:03:02.650] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:03:02.664] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:03:02.670] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:03:02.670] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:03:02.670] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:03:02.694] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:03:02.694] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:03:02.694] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:03:02.694] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:03:02.694] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:03:02.701] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:03:02.701] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:03:02.724] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:03:02.724] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:03:02.724] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:03:02.724] INFO: readReadbackCal: data/ml335/readbackCal_C0.dat .. data/ml335/readbackCal_C15.dat
[16:03:02.733] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:03:02.741] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:03:02.741] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:03:02.741] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:03:02.741] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:03:02.760] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:03:02.760] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:03:08.149] INFO: ######################################################################
[16:03:08.149] INFO: PixTestAlive::doTest()
[16:03:08.149] INFO: ######################################################################
[16:03:08.152] INFO: ----------------------------------------------------------------------
[16:03:08.152] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:03:08.152] INFO: ----------------------------------------------------------------------
[16:03:08.155] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:03:08.533] INFO: Expecting 41600 events.
[16:03:12.740] INFO: 41600 events read in total (3488ms).
[16:03:12.885] INFO: Test took 4730ms.
[16:03:12.895] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:12.895] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:03:12.895] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:03:13.165] INFO: PixTestAlive::aliveTest() done
[16:03:13.165] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:03:13.165] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 47 29 50 81 93 96 94 106 110 95 101 84 99 52 31 48
[16:03:13.186] INFO: ----------------------------------------------------------------------
[16:03:13.186] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:03:13.186] INFO: ----------------------------------------------------------------------
[16:03:13.189] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:03:13.567] INFO: Expecting 41600 events.
[16:03:16.482] INFO: 41600 events read in total (2200ms).
[16:03:16.482] INFO: Test took 3293ms.
[16:03:16.482] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:16.482] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:03:16.482] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:03:16.483] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:03:16.905] INFO: PixTestAlive::maskTest() done
[16:03:16.905] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:03:16.927] INFO: ----------------------------------------------------------------------
[16:03:16.927] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:03:16.927] INFO: ----------------------------------------------------------------------
[16:03:16.930] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:03:17.308] INFO: Expecting 41600 events.
[16:03:21.489] INFO: 41600 events read in total (3466ms).
[16:03:21.491] INFO: Test took 4561ms.
[16:03:21.500] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:21.500] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:03:21.500] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:03:21.870] INFO: PixTestAlive::addressDecodingTest() done
[16:03:21.870] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:03:21.870] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[16:03:24.210] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:03:24.210] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:03:24.210] INFO: ----------------------------------------------------------------------
[16:03:24.210] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:03:24.210] INFO: ----------------------------------------------------------------------
[16:03:24.349] INFO: Expecting 768 events.
[16:03:25.478] INFO: 768 events read in total (414ms).
[16:03:25.478] INFO: Test took 1262ms.
[16:03:25.484] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:03:26.282] INFO: Expecting 41600 events.
[16:03:30.171] INFO: 41600 events read in total (3363ms).
[16:03:30.177] INFO: Test took 4693ms.
[16:03:30.353] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:30.353] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1027343
[16:03:30.353] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:03:30.354] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:03:30.426] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:03:31.091] INFO: Expecting 41600 events.
[16:03:35.080] INFO: 41600 events read in total (3462ms).
[16:03:35.088] INFO: Test took 4662ms.
[16:03:35.258] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:35.258] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1036397
[16:03:35.258] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:03:35.258] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:03:35.338] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:03:36.007] INFO: Expecting 41600 events.
[16:03:40.047] INFO: 41600 events read in total (3514ms).
[16:03:40.055] INFO: Test took 4717ms.
[16:03:40.227] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:40.227] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1039927
[16:03:40.227] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:03:40.227] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:03:40.304] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:03:40.968] INFO: Expecting 41600 events.
[16:03:45.002] INFO: 41600 events read in total (3507ms).
[16:03:45.010] INFO: Test took 4706ms.
[16:03:45.183] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:45.183] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1040350
[16:03:45.183] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:03:45.183] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:03:45.259] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:03:45.924] INFO: Expecting 41600 events.
[16:03:49.971] INFO: 41600 events read in total (3520ms).
[16:03:49.979] INFO: Test took 4720ms.
[16:03:50.149] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:50.149] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1041804
[16:03:50.149] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:03:50.150] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:03:50.225] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:03:50.890] INFO: Expecting 41600 events.
[16:03:54.940] INFO: 41600 events read in total (3523ms).
[16:03:54.948] INFO: Test took 4723ms.
[16:03:55.119] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:55.119] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1039149
[16:03:55.119] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:03:55.120] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:03:55.195] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:03:55.860] INFO: Expecting 41600 events.
[16:03:59.905] INFO: 41600 events read in total (3519ms).
[16:03:59.913] INFO: Test took 4718ms.
[16:04:00.084] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:00.084] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1039593
[16:04:00.084] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:04:00.085] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:04:00.161] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:00.826] INFO: Expecting 41600 events.
[16:04:04.875] INFO: 41600 events read in total (3523ms).
[16:04:04.883] INFO: Test took 4722ms.
[16:04:05.056] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:05.056] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1041286
[16:04:05.056] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:04:05.057] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:04:05.132] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:05.797] INFO: Expecting 41600 events.
[16:04:09.867] INFO: 41600 events read in total (3543ms).
[16:04:09.875] INFO: Test took 4743ms.
[16:04:10.046] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:10.046] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1041241
[16:04:10.046] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:04:10.047] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:04:10.122] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:10.787] INFO: Expecting 41600 events.
[16:04:14.858] INFO: 41600 events read in total (3544ms).
[16:04:14.866] INFO: Test took 4744ms.
[16:04:15.038] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:15.038] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1041305
[16:04:15.038] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:04:15.039] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:04:15.115] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:15.780] INFO: Expecting 41600 events.
[16:04:19.850] INFO: 41600 events read in total (3543ms).
[16:04:19.858] INFO: Test took 4743ms.
[16:04:20.028] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:20.028] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1041290
[16:04:20.028] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:04:20.028] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:04:20.105] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:20.769] INFO: Expecting 41600 events.
[16:04:24.841] INFO: 41600 events read in total (3545ms).
[16:04:24.849] INFO: Test took 4744ms.
[16:04:25.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:25.022] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1041837
[16:04:25.022] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:04:25.022] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:04:25.099] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:25.764] INFO: Expecting 41600 events.
[16:04:29.837] INFO: 41600 events read in total (3547ms).
[16:04:29.845] INFO: Test took 4746ms.
[16:04:30.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:30.022] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1040845
[16:04:30.022] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:04:30.022] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:04:30.098] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:30.763] INFO: Expecting 41600 events.
[16:04:34.826] INFO: 41600 events read in total (3536ms).
[16:04:34.834] INFO: Test took 4736ms.
[16:04:35.007] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:35.007] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1040680
[16:04:35.007] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:04:35.007] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:04:35.084] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:35.748] INFO: Expecting 41600 events.
[16:04:39.823] INFO: 41600 events read in total (3548ms).
[16:04:39.831] INFO: Test took 4747ms.
[16:04:40.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:40.002] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1041515
[16:04:40.002] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:04:40.002] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:04:40.080] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:40.744] INFO: Expecting 41600 events.
[16:04:44.797] INFO: 41600 events read in total (3526ms).
[16:04:44.805] INFO: Test took 4725ms.
[16:04:44.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:44.979] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1038886
[16:04:44.979] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:04:44.980] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:04:45.058] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:45.724] INFO: Expecting 41600 events.
[16:04:49.783] INFO: 41600 events read in total (3533ms).
[16:04:49.791] INFO: Test took 4733ms.
[16:04:49.964] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:49.964] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1040736
[16:04:49.964] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:04:49.965] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:04:50.043] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:50.709] INFO: Expecting 41600 events.
[16:04:54.770] INFO: 41600 events read in total (3534ms).
[16:04:54.778] INFO: Test took 4735ms.
[16:04:54.950] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:54.950] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1042101
[16:04:54.950] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:04:54.951] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:04:55.029] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:04:55.695] INFO: Expecting 41600 events.
[16:04:59.708] INFO: 41600 events read in total (3486ms).
[16:04:59.716] INFO: Test took 4687ms.
[16:04:59.891] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:59.891] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1039994
[16:04:59.891] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:04:59.891] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:04:59.970] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:00.636] INFO: Expecting 41600 events.
[16:05:04.471] INFO: 41600 events read in total (3308ms).
[16:05:04.477] INFO: Test took 4507ms.
[16:05:04.649] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:04.649] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1029585
[16:05:04.649] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:05:04.649] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:05:04.943] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 176 eff = 0.998317
[16:05:04.943] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 178 eff = 0.998558
[16:05:04.944] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 167 eff = 0.996418
[16:05:04.944] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 175 eff = 0.994784
[16:05:04.944] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 184 eff = 0.993029
[16:05:04.944] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 168 eff = 0.99399
[16:05:04.944] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 174 eff = 0.993894
[16:05:04.944] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 175 eff = 0.994111
[16:05:04.944] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 157 eff = 0.993726
[16:05:04.945] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 167 eff = 0.991899
[16:05:04.945] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 157 eff = 0.992644
[16:05:04.945] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 158 eff = 0.993846
[16:05:04.945] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 175 eff = 0.992957
[16:05:04.945] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 149 eff = 0.996563
[16:05:04.945] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 164 eff = 0.998317
[16:05:04.945] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 173 eff = 0.998269
[16:05:07.186] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:05:07.186] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:05:07.186] INFO: ----------------------------------------------------------------------
[16:05:07.186] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:05:07.186] INFO: ----------------------------------------------------------------------
[16:05:07.186] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:05:07.186] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:05:07.186] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:05:07.186] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:05:07.186] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:05:07.186] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:05:07.186] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:05:07.186] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:05:07.186] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:05:07.186] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:05:07.186] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:05:07.186] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:05:07.204] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:05:07.874] INFO: Expecting 208000 events.
[16:05:26.115] INFO: 208000 events read in total (17712ms).
[16:05:26.140] INFO: Test took 18936ms.
[16:05:27.233] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:27.233] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4879072
[16:05:27.233] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:05:27.233] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:05:27.784] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:05:27.784] INFO: number of red-efficiency pixels: 324 220 597 904 1068 1051 1067 992 1054 1269 1111 1055 1051 514 301 278
[16:05:27.784] INFO: number of X-ray hits detected: 186271 141926 233822 349972 374019 369976 368760 347364 376896 392982 385545 360160 372339 239181 144404 168895
[16:05:27.784] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:05:27.784] INFO: number of Vcal hits detected: 207659 207764 207285 206926 206671 206727 206696 206824 206757 206365 206606 206700 206679 207400 207673 207709
[16:05:27.784] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.4 99.4 99.4 99.5 99.4 99.3 99.4 99.4 99.4 99.7 99.9 99.9
[16:05:27.784] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.5 99.4 99.4 99.4 99.4 99.4 99.2 99.3 99.4 99.4 99.7 99.8 99.9
[16:05:27.784] INFO: X-ray hit rate [MHz/cm2]: 54.6 41.6 68.5 102.6 109.6 108.4 108.1 101.8 110.5 115.2 113.0 105.6 109.1 70.1 42.3 49.5
[16:05:27.784] INFO: PixTestHighRate::doXPixelAlive() done
[16:05:27.829] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:05:27.829] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:05:27.829] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:05:27.829] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:05:27.829] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:05:27.829] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:05:27.829] INFO: PixTest:: pg_setup set to default.
[16:05:31.338] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:05:31.338] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C9_V0 -> highRate_xraymap_mod
[16:05:33.162] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:05:33.163] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 381.9mA
[16:05:33.163] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 465.5mA
[16:05:33.163] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[16:05:33.163] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:05:33.163] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:05:33.164] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:05:33.164] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:05:33.164] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:05:33.164] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:05:33.164] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:05:33.164] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:05:33.164] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:05:33.164] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:05:33.257] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:05:33.257] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:05:33.266] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:05:33.267] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:05:33.267] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:05:33.267] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:05:33.267] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:05:33.267] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:05:33.269] QUIET: Connection to board 43 closed.
[16:05:34.470] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master