Test Date: 1973-11-29 16:33
Analysis date: 2016-06-14 18:11
Logfile
hrData_40.log
[11:00:36.077] INFO: *** Welcome to pxar ***
[11:00:36.077] INFO: *** Today: 2016/06/13
[11:00:36.102] INFO: *** Version: v1.9.0-814-g7497
[11:00:36.102] INFO: readRocDacs: data/mp146/dacParameters35_C0.dat .. data/mp146/dacParameters35_C15.dat
[11:00:36.103] INFO: readTbmDacs: data/mp146/tbmParameters_C0a.dat .. data/mp146/tbmParameters_C0b.dat
[11:00:36.104] INFO: readMaskFile: data/mp146/defaultMaskFile.dat
[11:00:36.104] INFO: readTrimFile: data/mp146/trimParameters35_C0.dat .. data/mp146/trimParameters35_C15.dat
[11:00:36.253] INFO: clk: 4
[11:00:36.253] INFO: ctr: 4
[11:00:36.253] INFO: sda: 19
[11:00:36.253] INFO: tin: 9
[11:00:36.253] INFO: level: 15
[11:00:36.253] INFO: triggerdelay: 0
[11:00:36.253] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[11:00:36.253] INFO: Log level: DEBUG
[11:00:36.265] QUIET: Connection to board DTB_WRMNMR opened.
[11:00:36.268] 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)
------------------------------------------------------
[11:00:36.271] INFO: RPC call hashes of host and DTB match: 398089610
[11:00:37.873] INFO: DUT info:
[11:00:37.873] INFO: The DUT currently contains the following objects:
[11:00:37.873] INFO: 2 TBM Cores tbm08c (2 ON)
[11:00:37.873] INFO: TBM Core alpha (0): 7 registers set
[11:00:37.873] INFO: TBM Core beta (1): 7 registers set
[11:00:37.873] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:00:37.873] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.873] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.873] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.873] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.873] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.873] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.873] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.873] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.873] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.873] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.874] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.874] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.874] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.874] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.874] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.874] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:00:37.874] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:00:37.875] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:00:37.876] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:00:37.877] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:00:37.879] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34852864
[11:00:37.879] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x268e0d0
[11:00:37.879] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2430360
[11:00:37.879] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f9da5d94010
[11:00:37.879] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f9dabfff510
[11:00:37.879] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34861056 fPxarMemory = 0x7f9da5d94010
[11:00:37.880] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.1mA
[11:00:37.882] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[11:00:37.882] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[11:00:38.380] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2a78d60
[11:00:38.443] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:00:38.443] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:00:38.444] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:00:38.449] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:00:38.449] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:00:38.449] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:00:38.459] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:00:38.459] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:00:38.466] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:00:38.466] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:00:38.485] INFO: PixTestCmd::init()
[11:00:38.497] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:00:38.497] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:00:38.497] INFO: readGainPedestalParameters data/mp146/phCalibrationFitErr35_C0.dat .. data/mp146/phCalibrationFitErr35_C15.dat
[11:00:38.742] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:00:38.742] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:00:38.756] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:00:38.761] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:00:38.761] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:00:38.761] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:00:38.782] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:00:38.782] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:00:38.782] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:00:38.782] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:00:38.782] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:00:38.789] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:00:38.789] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:00:38.806] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:00:38.806] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:00:38.806] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:00:38.806] INFO: readReadbackCal: data/mp146/readbackCal_C0.dat .. data/mp146/readbackCal_C15.dat
[11:00:38.815] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:00:38.823] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[11:00:38.823] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[11:00:38.823] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[11:00:38.823] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[11:00:38.835] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:00:38.835] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:00:43.304] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[11:00:45.624] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[11:00:47.093] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[11:00:47.093] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[11:00:47.093] INFO: ----------------------------------------------------------------------
[11:00:47.093] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[11:00:47.093] INFO: ----------------------------------------------------------------------
[11:00:48.067] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[11:01:00.301] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[11:01:00.305] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:01:27.603] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1205458 events.
[11:01:33.892] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1205458, pixels seen in all events: 5786803
[11:01:34.074] INFO: Resuming triggers.
[11:01:46.313] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[11:01:46.350] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:02:13.406] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1205680 events.
[11:02:19.261] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1205680, pixels seen in all events: 5783087
[11:02:19.433] INFO: Resuming triggers.
[11:02:31.672] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[11:02:31.709] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:02:58.778] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1205752 events.
[11:03:04.658] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1205752, pixels seen in all events: 5782410
[11:03:04.823] INFO: Resuming triggers.
[11:03:17.066] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[11:03:17.104] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:03:44.249] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1206087 events.
[11:03:50.024] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1206087, pixels seen in all events: 5778397
[11:03:50.272] INFO: Resuming triggers.
[11:04:01.397] INFO: data taking finished, elapsed time: 60 seconds.
[11:04:01.593] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:04:26.180] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1096001 events.
[11:04:31.515] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1096001, pixels seen in all events: 5255150
[11:04:31.677] INFO: PixTest:: pg_setup set to default.
[11:04:31.718] INFO: PixTestXray::doPhRun() done
[11:05:19.853] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:05:19.853] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = qMap_02ma_C8_V0 -> qMap_02ma_mod
[11:05:44.029] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:05:44.030] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 392.3mA
[11:05:44.031] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[11:05:44.031] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -1.0 C
[11:05:44.031] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:05:44.031] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:05:44.031] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:05:44.031] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:05:44.031] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:05:44.031] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:05:44.031] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:05:44.031] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:05:44.031] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:05:44.031] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:05:44.031] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:05:44.032] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:05:44.032] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:05:44.032] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:05:44.032] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:05:44.032] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[11:05:44.032] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:05:44.032] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:05:44.336] QUIET: Connection to board 43 closed.
[11:05:44.416] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[11:05:50.039] INFO: *** Welcome to pxar ***
[11:05:50.039] INFO: *** Today: 2016/06/13
[11:05:50.064] INFO: *** Version: v1.9.0-814-g7497
[11:05:50.064] INFO: readRocDacs: data/mp146/dacParameters35_C0.dat .. data/mp146/dacParameters35_C15.dat
[11:05:50.065] INFO: readTbmDacs: data/mp146/tbmParameters_C0a.dat .. data/mp146/tbmParameters_C0b.dat
[11:05:50.065] INFO: readMaskFile: data/mp146/defaultMaskFile.dat
[11:05:50.065] INFO: readTrimFile: data/mp146/trimParameters35_C0.dat .. data/mp146/trimParameters35_C15.dat
[11:05:50.218] INFO: clk: 4
[11:05:50.218] INFO: ctr: 4
[11:05:50.218] INFO: sda: 19
[11:05:50.218] INFO: tin: 9
[11:05:50.218] INFO: level: 15
[11:05:50.218] INFO: triggerdelay: 0
[11:05:50.218] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[11:05:50.218] INFO: Log level: DEBUG
[11:05:50.229] QUIET: Connection to board DTB_WRMNMR opened.
[11:05:50.234] 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)
------------------------------------------------------
[11:05:50.236] INFO: RPC call hashes of host and DTB match: 398089610
[11:05:51.840] INFO: DUT info:
[11:05:51.840] INFO: The DUT currently contains the following objects:
[11:05:51.840] INFO: 2 TBM Cores tbm08c (2 ON)
[11:05:51.840] INFO: TBM Core alpha (0): 7 registers set
[11:05:51.840] INFO: TBM Core beta (1): 7 registers set
[11:05:51.840] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:05:51.840] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.840] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.840] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.840] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.840] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.840] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.840] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.840] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.840] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.840] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.840] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.840] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.840] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.840] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.840] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.840] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:05:51.841] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:05:51.842] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:05:51.843] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:05:51.844] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:05:51.844] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:05:51.844] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:05:51.844] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:05:51.844] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:05:51.844] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:05:51.844] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:05:51.844] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:05:51.844] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:05:51.844] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:05:51.844] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:05:51.846] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31395840
[11:05:51.846] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1efc0d0
[11:05:51.846] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1c9e360
[11:05:51.846] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f754dd94010
[11:05:51.846] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f7553fff510
[11:05:51.846] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31404032 fPxarMemory = 0x7f754dd94010
[11:05:51.847] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.1mA
[11:05:51.848] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 469.5mA
[11:05:51.849] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[11:05:52.343] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x22e6c90
[11:05:52.409] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:05:52.409] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:05:52.409] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:05:52.415] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:05:52.415] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:05:52.415] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:05:52.426] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:05:52.426] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:05:52.448] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:05:52.448] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:05:52.458] INFO: PixTestCmd::init()
[11:05:52.467] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:05:52.467] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:05:52.467] INFO: readGainPedestalParameters data/mp146/phCalibrationFitErr35_C0.dat .. data/mp146/phCalibrationFitErr35_C15.dat
[11:05:52.711] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:05:52.711] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:05:52.727] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:05:52.733] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:05:52.733] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:05:52.733] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:05:52.756] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:05:52.756] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:05:52.756] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:05:52.756] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:05:52.756] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:05:52.762] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:05:52.762] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:05:52.781] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:05:52.781] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:05:52.781] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:05:52.781] INFO: readReadbackCal: data/mp146/readbackCal_C0.dat .. data/mp146/readbackCal_C15.dat
[11:05:52.791] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:05:52.798] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[11:05:52.798] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[11:05:52.798] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[11:05:52.798] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[11:05:52.815] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:05:52.815] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:06:01.768] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[11:06:04.264] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 6
[11:06:06.328] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[11:06:08.445] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[11:06:08.445] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[11:06:08.445] INFO: ----------------------------------------------------------------------
[11:06:08.445] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[11:06:08.445] INFO: ----------------------------------------------------------------------
[11:06:09.418] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[11:06:17.250] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[11:06:17.254] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:06:42.623] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 771698 events.
[11:06:54.424] DEBUG: <PixTestXray.cc/processData:L823> # events read: 771698, pixels seen in all events: 10993441
[11:06:54.679] INFO: Resuming triggers.
[11:07:02.515] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[11:07:02.553] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:07:27.760] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 771916 events.
[11:07:39.556] DEBUG: <PixTestXray.cc/processData:L823> # events read: 771916, pixels seen in all events: 10989395
[11:07:39.756] INFO: Resuming triggers.
[11:07:47.592] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:07:47.659] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:08:12.927] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 771969 events.
[11:08:24.246] DEBUG: <PixTestXray.cc/processData:L823> # events read: 771969, pixels seen in all events: 10988604
[11:08:24.486] INFO: Resuming triggers.
[11:08:32.325] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[11:08:32.363] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:08:57.508] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 772226 events.
[11:09:08.876] DEBUG: <PixTestXray.cc/processData:L823> # events read: 772226, pixels seen in all events: 10984527
[11:09:09.050] INFO: Resuming triggers.
[11:09:16.888] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[11:09:16.927] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:09:42.140] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 772176 events.
[11:09:53.426] DEBUG: <PixTestXray.cc/processData:L823> # events read: 772176, pixels seen in all events: 10985353
[11:09:53.659] INFO: Resuming triggers.
[11:10:01.500] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[11:10:01.531] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:10:26.771] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 772423 events.
[11:10:38.064] DEBUG: <PixTestXray.cc/processData:L823> # events read: 772423, pixels seen in all events: 10982660
[11:10:38.266] INFO: Resuming triggers.
[11:10:46.106] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[11:10:46.171] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:11:11.434] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 772422 events.
[11:11:22.706] DEBUG: <PixTestXray.cc/processData:L823> # events read: 772422, pixels seen in all events: 10983298
[11:11:22.942] INFO: Resuming triggers.
[11:11:28.152] INFO: data taking finished, elapsed time: 60 seconds.
[11:11:28.349] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:11:45.180] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 513277 events.
[11:11:52.693] DEBUG: <PixTestXray.cc/processData:L823> # events read: 513277, pixels seen in all events: 7296620
[11:11:52.818] INFO: PixTest:: pg_setup set to default.
[11:11:52.860] INFO: PixTestXray::doPhRun() done
[11:12:38.261] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:12:38.261] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = qMap_06ma_C10_V0 -> qMap_06ma_mod
[11:12:43.509] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:12:43.510] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 391.5mA
[11:12:43.511] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 470.3mA
[11:12:43.511] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[11:12:43.511] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:12:43.511] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:12:43.511] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:12:43.511] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:12:43.511] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:12:43.511] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:12:43.511] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:12:43.511] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:12:43.511] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:12:43.511] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:12:43.511] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:12:43.511] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:12:43.511] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:12:43.512] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:12:43.512] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:12:43.512] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[11:12:43.512] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:12:43.512] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:12:43.843] QUIET: Connection to board 43 closed.
[11:12:43.922] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[10:56:17.049] INFO: *** Welcome to pxar ***
[10:56:17.049] INFO: *** Today: 2016/06/13
[10:56:17.074] INFO: *** Version: v1.9.0-814-g7497
[10:56:17.074] INFO: readRocDacs: data/mp146/dacParameters35_C0.dat .. data/mp146/dacParameters35_C15.dat
[10:56:17.075] INFO: readTbmDacs: data/mp146/tbmParameters_C0a.dat .. data/mp146/tbmParameters_C0b.dat
[10:56:17.075] INFO: readMaskFile: data/mp146/defaultMaskFile.dat
[10:56:17.075] INFO: readTrimFile: data/mp146/trimParameters35_C0.dat .. data/mp146/trimParameters35_C15.dat
[10:56:17.227] INFO: clk: 4
[10:56:17.227] INFO: ctr: 4
[10:56:17.227] INFO: sda: 19
[10:56:17.227] INFO: tin: 9
[10:56:17.227] INFO: level: 15
[10:56:17.227] INFO: triggerdelay: 0
[10:56:17.227] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:56:17.227] INFO: Log level: DEBUG
[10:56:17.239] QUIET: Connection to board DTB_WRMNMR opened.
[10:56:17.242] 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)
------------------------------------------------------
[10:56:17.245] INFO: RPC call hashes of host and DTB match: 398089610
[10:56:18.849] INFO: DUT info:
[10:56:18.849] INFO: The DUT currently contains the following objects:
[10:56:18.849] INFO: 2 TBM Cores tbm08c (2 ON)
[10:56:18.849] INFO: TBM Core alpha (0): 7 registers set
[10:56:18.849] INFO: TBM Core beta (1): 7 registers set
[10:56:18.849] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:56:18.849] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.849] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.849] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.849] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.849] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.849] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.849] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.849] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.849] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.849] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.849] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.849] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.849] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.849] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.849] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.849] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[10:56:18.850] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[10:56:18.851] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[10:56:18.852] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[10:56:18.853] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[10:56:18.853] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:56:18.853] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[10:56:18.853] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[10:56:18.853] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[10:56:18.853] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[10:56:18.853] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:56:18.853] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:56:18.855] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31535104
[10:56:18.855] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x28e5bd0
[10:56:18.855] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2687e60
[10:56:18.855] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f8059d94010
[10:56:18.855] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f805ffff510
[10:56:18.855] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31543296 fPxarMemory = 0x7f8059d94010
[10:56:18.856] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.1mA
[10:56:18.857] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[10:56:18.857] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[10:56:19.352] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2cd0850
[10:56:19.413] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[10:56:19.413] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[10:56:19.413] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[10:56:19.419] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[10:56:19.419] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[10:56:19.419] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[10:56:19.428] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[10:56:19.428] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[10:56:19.453] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[10:56:19.453] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[10:56:19.473] INFO: PixTestCmd::init()
[10:56:19.481] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[10:56:19.482] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[10:56:19.482] INFO: readGainPedestalParameters data/mp146/phCalibrationFitErr35_C0.dat .. data/mp146/phCalibrationFitErr35_C15.dat
[10:56:19.725] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[10:56:19.725] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[10:56:19.738] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[10:56:19.744] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[10:56:19.744] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[10:56:19.744] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[10:56:19.765] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[10:56:19.765] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[10:56:19.765] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[10:56:19.765] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[10:56:19.765] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[10:56:19.771] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[10:56:19.771] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[10:56:19.786] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[10:56:19.786] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[10:56:19.786] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[10:56:19.786] INFO: readReadbackCal: data/mp146/readbackCal_C0.dat .. data/mp146/readbackCal_C15.dat
[10:56:19.798] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[10:56:19.806] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[10:56:19.806] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[10:56:19.806] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[10:56:19.806] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[10:56:19.818] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[10:56:19.819] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[10:56:25.797] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[10:56:25.797] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[10:56:25.797] INFO: ----------------------------------------------------------------------
[10:56:25.797] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:56:25.797] INFO: ----------------------------------------------------------------------
[10:56:25.937] INFO: Expecting 768 events.
[10:56:27.071] INFO: 768 events read in total (415ms).
[10:56:27.260] INFO: Test took 1457ms.
[10:56:27.266] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:56:27.934] INFO: Expecting 41600 events.
[10:56:31.044] INFO: 41600 events read in total (2583ms).
[10:56:31.049] INFO: Test took 3783ms.
[10:56:31.130] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:31.130] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 471579
[10:56:31.130] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[10:56:31.131] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:56:31.168] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:56:31.833] INFO: Expecting 41600 events.
[10:56:35.121] INFO: 41600 events read in total (2761ms).
[10:56:35.125] INFO: Test took 3957ms.
[10:56:35.204] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:35.204] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 484218
[10:56:35.204] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[10:56:35.204] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:56:35.244] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:56:35.909] INFO: Expecting 41600 events.
[10:56:39.266] INFO: 41600 events read in total (2830ms).
[10:56:39.269] INFO: Test took 4025ms.
[10:56:39.349] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:39.349] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 488583
[10:56:39.349] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[10:56:39.350] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:56:39.390] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:56:40.055] INFO: Expecting 41600 events.
[10:56:43.414] INFO: 41600 events read in total (2833ms).
[10:56:43.418] INFO: Test took 4028ms.
[10:56:43.499] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:43.499] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487298
[10:56:43.499] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[10:56:43.499] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:56:43.539] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:56:44.204] INFO: Expecting 41600 events.
[10:56:47.564] INFO: 41600 events read in total (2833ms).
[10:56:47.568] INFO: Test took 4029ms.
[10:56:47.648] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:47.648] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487917
[10:56:47.648] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[10:56:47.648] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:56:47.689] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:56:48.354] INFO: Expecting 41600 events.
[10:56:51.716] INFO: 41600 events read in total (2836ms).
[10:56:51.720] INFO: Test took 4031ms.
[10:56:51.801] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:51.801] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487952
[10:56:51.801] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[10:56:51.801] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:56:51.841] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:56:52.506] INFO: Expecting 41600 events.
[10:56:55.869] INFO: 41600 events read in total (2836ms).
[10:56:55.872] INFO: Test took 4031ms.
[10:56:55.954] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:55.954] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486765
[10:56:55.954] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[10:56:55.954] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:56:55.994] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:56:56.659] INFO: Expecting 41600 events.
[10:57:00.028] INFO: 41600 events read in total (2842ms).
[10:57:00.033] INFO: Test took 4039ms.
[10:57:00.114] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:00.115] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486180
[10:57:00.115] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[10:57:00.115] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:57:00.157] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:57:00.823] INFO: Expecting 41600 events.
[10:57:04.205] INFO: 41600 events read in total (2856ms).
[10:57:04.209] INFO: Test took 4052ms.
[10:57:04.288] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:04.288] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487185
[10:57:04.289] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[10:57:04.289] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:57:04.329] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:57:04.994] INFO: Expecting 41600 events.
[10:57:08.376] INFO: 41600 events read in total (2855ms).
[10:57:08.380] INFO: Test took 4051ms.
[10:57:08.459] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:08.459] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487170
[10:57:08.459] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[10:57:08.460] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:57:08.500] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:57:09.165] INFO: Expecting 41600 events.
[10:57:12.543] INFO: 41600 events read in total (2852ms).
[10:57:12.546] INFO: Test took 4046ms.
[10:57:12.626] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:12.626] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486312
[10:57:12.626] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[10:57:12.626] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:57:12.667] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:57:13.332] INFO: Expecting 41600 events.
[10:57:16.714] INFO: 41600 events read in total (2856ms).
[10:57:16.718] INFO: Test took 4051ms.
[10:57:16.797] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:16.797] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486719
[10:57:16.797] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[10:57:16.797] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:57:16.837] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:57:17.502] INFO: Expecting 41600 events.
[10:57:20.883] INFO: 41600 events read in total (2854ms).
[10:57:20.887] INFO: Test took 4050ms.
[10:57:20.966] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:20.966] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486418
[10:57:20.966] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[10:57:20.967] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:57:21.007] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:57:21.672] INFO: Expecting 41600 events.
[10:57:25.045] INFO: 41600 events read in total (2846ms).
[10:57:25.048] INFO: Test took 4041ms.
[10:57:25.128] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:25.128] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 485874
[10:57:25.128] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[10:57:25.129] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:57:25.169] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:57:25.834] INFO: Expecting 41600 events.
[10:57:29.188] INFO: 41600 events read in total (2827ms).
[10:57:29.192] INFO: Test took 4023ms.
[10:57:29.271] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:29.271] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486644
[10:57:29.271] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[10:57:29.271] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:57:29.312] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:57:29.977] INFO: Expecting 41600 events.
[10:57:33.366] INFO: 41600 events read in total (2862ms).
[10:57:33.369] INFO: Test took 4057ms.
[10:57:33.449] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:33.449] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487207
[10:57:33.449] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[10:57:33.450] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:57:33.490] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:57:34.155] INFO: Expecting 41600 events.
[10:57:37.531] INFO: 41600 events read in total (2849ms).
[10:57:37.535] INFO: Test took 4045ms.
[10:57:37.615] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:37.615] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486869
[10:57:37.615] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[10:57:37.615] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:57:37.656] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:57:38.321] INFO: Expecting 41600 events.
[10:57:41.704] INFO: 41600 events read in total (2857ms).
[10:57:41.708] INFO: Test took 4052ms.
[10:57:41.788] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:41.788] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486423
[10:57:41.788] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[10:57:41.789] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:57:41.829] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:57:42.494] INFO: Expecting 41600 events.
[10:57:45.845] INFO: 41600 events read in total (2824ms).
[10:57:45.849] INFO: Test took 4020ms.
[10:57:45.929] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:45.929] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486105
[10:57:45.929] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[10:57:45.930] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:57:45.971] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:57:46.636] INFO: Expecting 41600 events.
[10:57:49.910] INFO: 41600 events read in total (2748ms).
[10:57:49.914] INFO: Test took 3943ms.
[10:57:49.992] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:49.992] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483235
[10:57:49.992] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[10:57:49.993] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:57:50.254] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 140 eff = 0.999591
[10:57:50.254] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 166 eff = 0.999736
[10:57:50.254] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 150 eff = 0.999327
[10:57:50.255] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 165 eff = 0.99899
[10:57:50.255] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 168 eff = 0.99887
[10:57:50.255] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 147 eff = 0.998774
[10:57:50.255] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 169 eff = 0.999231
[10:57:50.255] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 174 eff = 0.998966
[10:57:50.255] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 149 eff = 0.99875
[10:57:50.256] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 172 eff = 0.998606
[10:57:50.256] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 176 eff = 0.998726
[10:57:50.256] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 170 eff = 0.999183
[10:57:50.256] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 155 eff = 0.998966
[10:57:50.256] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 169 eff = 0.999519
[10:57:50.256] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 153 eff = 0.999736
[10:57:50.256] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 175 eff = 0.999712
[10:57:53.693] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[10:57:53.693] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[10:57:53.693] INFO: ----------------------------------------------------------------------
[10:57:53.693] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:57:53.693] INFO: ----------------------------------------------------------------------
[10:57:53.693] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[10:57:53.693] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[10:57:53.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[10:57:53.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[10:57:53.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[10:57:53.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[10:57:53.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[10:57:53.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[10:57:53.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[10:57:53.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[10:57:53.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[10:57:53.694] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[10:57:53.734] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:57:54.403] INFO: Expecting 208000 events.
[10:58:08.008] INFO: 208000 events read in total (13078ms).
[10:58:08.021] INFO: Test took 14287ms.
[10:58:08.405] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:08.405] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2152968
[10:58:08.405] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[10:58:08.405] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:58:08.778] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:58:08.778] INFO: number of red-efficiency pixels: 113 65 190 214 273 280 227 257 289 286 263 227 262 172 83 93
[10:58:08.778] INFO: number of X-ray hits detected: 83164 60584 100262 151982 167014 164182 161621 146594 161512 171115 168470 156462 155720 101832 60532 75362
[10:58:08.778] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:58:08.778] INFO: number of Vcal hits detected: 207886 207933 207804 207780 207715 207701 207765 207733 207701 207693 207723 207760 207727 207822 207917 207904
[10:58:08.778] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[10:58:08.778] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[10:58:08.778] INFO: X-ray hit rate [MHz/cm2]: 24.4 17.8 29.4 44.5 49.0 48.1 47.4 43.0 47.3 50.2 49.4 45.9 45.6 29.8 17.7 22.1
[10:58:08.778] INFO: PixTestHighRate::doXPixelAlive() done
[10:58:08.822] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[10:58:08.822] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[10:58:08.822] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[10:58:08.823] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[10:58:08.823] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[10:58:08.823] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[10:58:08.823] INFO: PixTest:: pg_setup set to default.
[10:58:16.341] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[10:58:16.341] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[10:58:18.949] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:58:18.950] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 391.5mA
[10:58:18.950] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 469.5mA
[10:58:18.951] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[10:58:18.951] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:58:18.951] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:58:18.951] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:58:18.951] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:58:18.951] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:58:18.951] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:58:18.951] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:58:18.951] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:58:18.951] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:58:18.951] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:58:19.043] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:58:19.043] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:58:19.043] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:58:19.043] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:58:19.043] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:58:19.043] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:58:19.044] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:58:19.044] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:58:19.046] QUIET: Connection to board 43 closed.
[10:58:19.126] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[10:53:10.791] INFO: *** Welcome to pxar ***
[10:53:10.791] INFO: *** Today: 2016/06/13
[10:53:10.815] INFO: *** Version: v1.9.0-814-g7497
[10:53:10.815] INFO: readRocDacs: data/mp146/dacParameters35_C0.dat .. data/mp146/dacParameters35_C15.dat
[10:53:10.816] INFO: readTbmDacs: data/mp146/tbmParameters_C0a.dat .. data/mp146/tbmParameters_C0b.dat
[10:53:10.816] INFO: readMaskFile: data/mp146/defaultMaskFile.dat
[10:53:10.816] INFO: readTrimFile: data/mp146/trimParameters35_C0.dat .. data/mp146/trimParameters35_C15.dat
[10:53:10.965] INFO: clk: 4
[10:53:10.965] INFO: ctr: 4
[10:53:10.965] INFO: sda: 19
[10:53:10.965] INFO: tin: 9
[10:53:10.965] INFO: level: 15
[10:53:10.965] INFO: triggerdelay: 0
[10:53:10.965] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:53:10.965] INFO: Log level: DEBUG
[10:53:10.976] QUIET: Connection to board DTB_WRMNMR opened.
[10:53:10.980] 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)
------------------------------------------------------
[10:53:10.982] INFO: RPC call hashes of host and DTB match: 398089610
[10:53:12.584] INFO: DUT info:
[10:53:12.584] INFO: The DUT currently contains the following objects:
[10:53:12.584] INFO: 2 TBM Cores tbm08c (2 ON)
[10:53:12.584] INFO: TBM Core alpha (0): 7 registers set
[10:53:12.584] INFO: TBM Core beta (1): 7 registers set
[10:53:12.584] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:53:12.584] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.584] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.584] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.584] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.584] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.584] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.584] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.585] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.585] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.585] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.585] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.585] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.585] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.585] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.585] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.585] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[10:53:12.585] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[10:53:12.586] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[10:53:12.587] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:53:12.588] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:53:12.590] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35168256
[10:53:12.590] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1c74bd0
[10:53:12.590] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1a16e60
[10:53:12.590] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f5e2dd94010
[10:53:12.590] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f5e33fff510
[10:53:12.590] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35176448 fPxarMemory = 0x7f5e2dd94010
[10:53:12.591] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.1mA
[10:53:12.593] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[10:53:12.593] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[10:53:13.089] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x205f8b0
[10:53:13.153] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[10:53:13.153] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[10:53:13.153] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[10:53:13.158] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[10:53:13.158] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[10:53:13.159] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[10:53:13.175] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[10:53:13.175] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[10:53:13.191] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[10:53:13.192] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[10:53:13.207] INFO: PixTestCmd::init()
[10:53:13.212] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[10:53:13.212] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[10:53:13.212] INFO: readGainPedestalParameters data/mp146/phCalibrationFitErr35_C0.dat .. data/mp146/phCalibrationFitErr35_C15.dat
[10:53:13.461] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[10:53:13.462] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[10:53:13.477] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[10:53:13.484] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[10:53:13.484] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[10:53:13.484] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[10:53:13.506] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[10:53:13.506] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[10:53:13.506] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[10:53:13.506] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[10:53:13.506] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[10:53:13.512] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[10:53:13.512] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[10:53:13.530] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[10:53:13.530] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[10:53:13.530] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[10:53:13.530] INFO: readReadbackCal: data/mp146/readbackCal_C0.dat .. data/mp146/readbackCal_C15.dat
[10:53:13.539] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[10:53:13.547] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[10:53:13.547] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[10:53:13.547] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[10:53:13.547] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[10:53:13.561] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[10:53:13.561] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[10:53:20.696] INFO: ######################################################################
[10:53:20.696] INFO: PixTestAlive::doTest()
[10:53:20.696] INFO: ######################################################################
[10:53:20.700] INFO: ----------------------------------------------------------------------
[10:53:20.700] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:53:20.700] INFO: ----------------------------------------------------------------------
[10:53:20.702] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:53:21.080] INFO: Expecting 41600 events.
[10:53:25.294] INFO: 41600 events read in total (3496ms).
[10:53:25.443] INFO: Test took 4741ms.
[10:53:25.457] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:25.457] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[10:53:25.457] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[10:53:25.732] INFO: PixTestAlive::aliveTest() done
[10:53:25.732] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:53:25.732] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 36 17 31 47 57 48 56 52 51 57 61 48 64 37 27 22
[10:53:25.756] INFO: ----------------------------------------------------------------------
[10:53:25.756] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:53:25.756] INFO: ----------------------------------------------------------------------
[10:53:25.759] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:53:26.137] INFO: Expecting 41600 events.
[10:53:29.088] INFO: 41600 events read in total (2236ms).
[10:53:29.088] INFO: Test took 3329ms.
[10:53:29.088] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:29.088] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[10:53:29.088] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[10:53:29.089] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:53:29.511] INFO: PixTestAlive::maskTest() done
[10:53:29.511] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:53:29.533] INFO: ----------------------------------------------------------------------
[10:53:29.533] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:53:29.533] INFO: ----------------------------------------------------------------------
[10:53:29.535] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:53:29.912] INFO: Expecting 41600 events.
[10:53:34.128] INFO: 41600 events read in total (3501ms).
[10:53:34.130] INFO: Test took 4595ms.
[10:53:34.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:34.141] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[10:53:34.141] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[10:53:34.510] INFO: PixTestAlive::addressDecodingTest() done
[10:53:34.510] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:53:34.511] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[10:53:55.933] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[10:53:55.933] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[10:53:55.933] INFO: ----------------------------------------------------------------------
[10:53:55.933] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:53:55.933] INFO: ----------------------------------------------------------------------
[10:53:56.072] INFO: Expecting 768 events.
[10:53:57.201] INFO: 768 events read in total (414ms).
[10:53:57.201] INFO: Test took 1262ms.
[10:53:57.207] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:53:58.005] INFO: Expecting 41600 events.
[10:54:01.412] INFO: 41600 events read in total (2881ms).
[10:54:01.417] INFO: Test took 4210ms.
[10:54:01.533] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:01.533] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 679694
[10:54:01.533] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[10:54:01.533] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:01.583] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:02.248] INFO: Expecting 41600 events.
[10:54:05.833] INFO: 41600 events read in total (3058ms).
[10:54:05.838] INFO: Test took 4255ms.
[10:54:05.954] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:05.954] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 694552
[10:54:05.954] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[10:54:05.954] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:06.008] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:06.673] INFO: Expecting 41600 events.
[10:54:10.280] INFO: 41600 events read in total (3081ms).
[10:54:10.284] INFO: Test took 4276ms.
[10:54:10.398] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:10.398] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 694606
[10:54:10.398] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[10:54:10.399] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:10.452] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:11.117] INFO: Expecting 41600 events.
[10:54:14.750] INFO: 41600 events read in total (3106ms).
[10:54:14.755] INFO: Test took 4303ms.
[10:54:14.872] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:14.872] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 695583
[10:54:14.872] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[10:54:14.873] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:14.926] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:15.591] INFO: Expecting 41600 events.
[10:54:19.212] INFO: 41600 events read in total (3094ms).
[10:54:19.216] INFO: Test took 4290ms.
[10:54:19.332] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:19.332] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 697675
[10:54:19.332] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[10:54:19.333] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:19.386] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:20.051] INFO: Expecting 41600 events.
[10:54:23.694] INFO: 41600 events read in total (3117ms).
[10:54:23.699] INFO: Test took 4313ms.
[10:54:23.816] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:23.816] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 695674
[10:54:23.816] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[10:54:23.816] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:23.869] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:24.534] INFO: Expecting 41600 events.
[10:54:28.169] INFO: 41600 events read in total (3108ms).
[10:54:28.174] INFO: Test took 4305ms.
[10:54:28.289] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:28.289] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696969
[10:54:28.289] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[10:54:28.290] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:28.344] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:29.009] INFO: Expecting 41600 events.
[10:54:32.653] INFO: 41600 events read in total (3117ms).
[10:54:32.658] INFO: Test took 4314ms.
[10:54:32.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:32.775] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696335
[10:54:32.775] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[10:54:32.776] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:32.829] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:33.494] INFO: Expecting 41600 events.
[10:54:37.152] INFO: 41600 events read in total (3131ms).
[10:54:37.157] INFO: Test took 4328ms.
[10:54:37.275] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:37.275] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 697513
[10:54:37.275] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[10:54:37.276] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:37.330] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:37.996] INFO: Expecting 41600 events.
[10:54:41.625] INFO: 41600 events read in total (3102ms).
[10:54:41.630] INFO: Test took 4300ms.
[10:54:41.747] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:41.747] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 697682
[10:54:41.747] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[10:54:41.747] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:41.801] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:42.466] INFO: Expecting 41600 events.
[10:54:46.117] INFO: 41600 events read in total (3124ms).
[10:54:46.121] INFO: Test took 4320ms.
[10:54:46.239] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:46.239] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 698335
[10:54:46.239] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[10:54:46.239] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:46.293] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:46.958] INFO: Expecting 41600 events.
[10:54:50.608] INFO: 41600 events read in total (3124ms).
[10:54:50.612] INFO: Test took 4319ms.
[10:54:50.729] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:50.729] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 695006
[10:54:50.729] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[10:54:50.730] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:50.783] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:51.448] INFO: Expecting 41600 events.
[10:54:55.106] INFO: 41600 events read in total (3131ms).
[10:54:55.111] INFO: Test took 4328ms.
[10:54:55.227] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:55.227] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 697090
[10:54:55.227] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[10:54:55.227] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:55.281] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:54:55.945] INFO: Expecting 41600 events.
[10:54:59.590] INFO: 41600 events read in total (3118ms).
[10:54:59.594] INFO: Test took 4313ms.
[10:54:59.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:59.714] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 697900
[10:54:59.714] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[10:54:59.714] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:54:59.768] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:55:00.433] INFO: Expecting 41600 events.
[10:55:04.074] INFO: 41600 events read in total (3114ms).
[10:55:04.079] INFO: Test took 4311ms.
[10:55:04.194] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:04.194] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 697398
[10:55:04.194] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[10:55:04.194] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:55:04.248] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:55:04.913] INFO: Expecting 41600 events.
[10:55:08.556] INFO: 41600 events read in total (3116ms).
[10:55:08.560] INFO: Test took 4312ms.
[10:55:08.677] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:08.677] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 695752
[10:55:08.677] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[10:55:08.677] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:55:08.731] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:55:09.396] INFO: Expecting 41600 events.
[10:55:13.034] INFO: 41600 events read in total (3112ms).
[10:55:13.039] INFO: Test took 4308ms.
[10:55:13.157] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:13.157] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696876
[10:55:13.158] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[10:55:13.158] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:55:13.212] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:55:13.877] INFO: Expecting 41600 events.
[10:55:17.527] INFO: 41600 events read in total (3123ms).
[10:55:17.532] INFO: Test took 4320ms.
[10:55:17.648] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:17.648] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696754
[10:55:17.648] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[10:55:17.649] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:55:17.703] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:55:18.368] INFO: Expecting 41600 events.
[10:55:22.004] INFO: 41600 events read in total (3110ms).
[10:55:22.008] INFO: Test took 4305ms.
[10:55:22.125] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:22.125] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 695789
[10:55:22.125] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[10:55:22.125] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:55:22.180] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:55:22.846] INFO: Expecting 41600 events.
[10:55:26.379] INFO: 41600 events read in total (3006ms).
[10:55:26.384] INFO: Test took 4204ms.
[10:55:26.498] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:55:26.499] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 693765
[10:55:26.499] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[10:55:26.499] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:55:26.775] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 149 eff = 0.999303
[10:55:26.775] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 172 eff = 0.999591
[10:55:26.776] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 175 eff = 0.99863
[10:55:26.776] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 168 eff = 0.997957
[10:55:26.776] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 164 eff = 0.99762
[10:55:26.776] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 159 eff = 0.998101
[10:55:26.777] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 172 eff = 0.997764
[10:55:26.777] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 182 eff = 0.998197
[10:55:26.777] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 155 eff = 0.997356
[10:55:26.777] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 178 eff = 0.997524
[10:55:26.778] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 178 eff = 0.99762
[10:55:26.778] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 171 eff = 0.998197
[10:55:26.778] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 161 eff = 0.997957
[10:55:26.778] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 178 eff = 0.998822
[10:55:26.778] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 152 eff = 0.999567
[10:55:26.779] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 157 eff = 0.999519
[10:55:47.061] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[10:55:47.061] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[10:55:47.061] INFO: ----------------------------------------------------------------------
[10:55:47.061] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:55:47.061] INFO: ----------------------------------------------------------------------
[10:55:47.061] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[10:55:47.061] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[10:55:47.061] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[10:55:47.062] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[10:55:47.062] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[10:55:47.062] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[10:55:47.062] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[10:55:47.062] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[10:55:47.062] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[10:55:47.062] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[10:55:47.062] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[10:55:47.062] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[10:55:47.079] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:55:47.746] INFO: Expecting 208000 events.
[10:56:02.961] INFO: 208000 events read in total (14689ms).
[10:56:02.978] INFO: Test took 15899ms.
[10:56:03.523] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:03.523] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3183729
[10:56:03.523] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[10:56:03.523] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:56:03.961] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:56:03.961] INFO: number of red-efficiency pixels: 184 109 280 400 572 496 445 403 476 511 508 430 448 253 130 169
[10:56:03.961] INFO: number of X-ray hits detected: 124267 89734 149539 228197 248754 244903 241983 220248 242159 255994 250779 233416 231959 151333 91405 112499
[10:56:03.961] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:56:03.961] INFO: number of Vcal hits detected: 207810 207886 207698 207566 207362 207460 207514 207566 207488 207452 207439 207542 207515 207731 207862 207821
[10:56:03.961] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.7 99.8 99.8 99.9 99.9 99.9
[10:56:03.961] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.8 99.8 99.8 99.7 99.7 99.8 99.8 99.9 99.9 99.9
[10:56:03.961] INFO: X-ray hit rate [MHz/cm2]: 36.4 26.3 43.8 66.9 72.9 71.8 70.9 64.6 71.0 75.0 73.5 68.4 68.0 44.4 26.8 33.0
[10:56:03.962] INFO: PixTestHighRate::doXPixelAlive() done
[10:56:04.006] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[10:56:04.006] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[10:56:04.006] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[10:56:04.006] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[10:56:04.006] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[10:56:04.006] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[10:56:04.006] INFO: PixTest:: pg_setup set to default.
[10:56:09.277] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[10:56:09.277] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[10:56:11.613] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:56:11.614] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 391.5mA
[10:56:11.614] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 469.5mA
[10:56:11.614] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[10:56:11.615] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:56:11.615] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:56:11.615] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:56:11.615] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:56:11.615] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:56:11.615] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:56:11.615] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:56:11.615] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:56:11.615] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:56:11.615] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:56:11.706] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:56:11.706] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:56:11.715] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:56:11.715] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:56:11.715] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:56:11.715] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:56:11.715] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:56:11.715] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:56:11.718] QUIET: Connection to board 43 closed.
[10:56:11.797] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[10:50:34.165] INFO: *** Welcome to pxar ***
[10:50:34.165] INFO: *** Today: 2016/06/13
[10:50:34.191] INFO: *** Version: v1.9.0-814-g7497
[10:50:34.191] INFO: readRocDacs: data/mp146/dacParameters35_C0.dat .. data/mp146/dacParameters35_C15.dat
[10:50:34.192] INFO: readTbmDacs: data/mp146/tbmParameters_C0a.dat .. data/mp146/tbmParameters_C0b.dat
[10:50:34.192] INFO: readMaskFile: data/mp146/defaultMaskFile.dat
[10:50:34.192] INFO: readTrimFile: data/mp146/trimParameters35_C0.dat .. data/mp146/trimParameters35_C15.dat
[10:50:34.345] INFO: clk: 4
[10:50:34.345] INFO: ctr: 4
[10:50:34.345] INFO: sda: 19
[10:50:34.345] INFO: tin: 9
[10:50:34.345] INFO: level: 15
[10:50:34.345] INFO: triggerdelay: 0
[10:50:34.345] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:50:34.345] INFO: Log level: DEBUG
[10:50:34.357] QUIET: Connection to board DTB_WRMNMR opened.
[10:50:34.360] 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)
------------------------------------------------------
[10:50:34.363] INFO: RPC call hashes of host and DTB match: 398089610
[10:50:35.964] INFO: DUT info:
[10:50:35.964] INFO: The DUT currently contains the following objects:
[10:50:35.964] INFO: 2 TBM Cores tbm08c (2 ON)
[10:50:35.964] INFO: TBM Core alpha (0): 7 registers set
[10:50:35.964] INFO: TBM Core beta (1): 7 registers set
[10:50:35.964] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:50:35.964] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.964] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.964] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.964] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.964] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.964] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.964] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.965] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.965] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.965] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.965] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.965] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.965] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.965] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.965] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.965] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[10:50:35.965] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:50:35.966] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[10:50:35.967] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:50:35.968] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:50:35.970] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33492992
[10:50:35.970] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x10e6bd0
[10:50:35.970] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xe88e60
[10:50:35.970] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fb155d94010
[10:50:35.970] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fb15bfff510
[10:50:35.970] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33501184 fPxarMemory = 0x7fb155d94010
[10:50:35.971] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 388.3mA
[10:50:35.972] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[10:50:35.973] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[10:50:36.468] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x14d1870
[10:50:36.532] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[10:50:36.532] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[10:50:36.532] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[10:50:36.537] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[10:50:36.537] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[10:50:36.538] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[10:50:36.549] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[10:50:36.549] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[10:50:36.562] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[10:50:36.563] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[10:50:36.577] INFO: PixTestCmd::init()
[10:50:36.588] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[10:50:36.588] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[10:50:36.588] INFO: readGainPedestalParameters data/mp146/phCalibrationFitErr35_C0.dat .. data/mp146/phCalibrationFitErr35_C15.dat
[10:50:36.841] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[10:50:36.842] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[10:50:36.857] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[10:50:36.862] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[10:50:36.862] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[10:50:36.862] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[10:50:36.885] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[10:50:36.885] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[10:50:36.885] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[10:50:36.885] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[10:50:36.885] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[10:50:36.891] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[10:50:36.891] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[10:50:36.907] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[10:50:36.907] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[10:50:36.907] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[10:50:36.907] INFO: readReadbackCal: data/mp146/readbackCal_C0.dat .. data/mp146/readbackCal_C15.dat
[10:50:36.916] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[10:50:36.923] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[10:50:36.923] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[10:50:36.923] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[10:50:36.923] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[10:50:36.940] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[10:50:36.940] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[10:50:46.453] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[10:50:46.453] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[10:50:46.453] INFO: ----------------------------------------------------------------------
[10:50:46.453] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:50:46.453] INFO: ----------------------------------------------------------------------
[10:50:46.594] INFO: Expecting 768 events.
[10:50:47.728] INFO: 768 events read in total (415ms).
[10:50:47.917] INFO: Test took 1458ms.
[10:50:47.923] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:50:48.592] INFO: Expecting 41600 events.
[10:50:52.209] INFO: 41600 events read in total (3090ms).
[10:50:52.214] INFO: Test took 4290ms.
[10:50:52.364] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:52.364] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 870479
[10:50:52.364] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[10:50:52.365] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:50:52.427] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:50:53.092] INFO: Expecting 41600 events.
[10:50:56.863] INFO: 41600 events read in total (3244ms).
[10:50:56.869] INFO: Test took 4442ms.
[10:50:57.011] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:57.011] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 883648
[10:50:57.011] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[10:50:57.012] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:50:57.077] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:50:57.743] INFO: Expecting 41600 events.
[10:51:01.573] INFO: 41600 events read in total (3304ms).
[10:51:01.578] INFO: Test took 4500ms.
[10:51:01.722] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:01.722] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 890268
[10:51:01.722] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[10:51:01.722] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:01.789] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:02.454] INFO: Expecting 41600 events.
[10:51:06.292] INFO: 41600 events read in total (3311ms).
[10:51:06.297] INFO: Test took 4508ms.
[10:51:06.442] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:06.442] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 888362
[10:51:06.442] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[10:51:06.442] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:06.509] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:07.173] INFO: Expecting 41600 events.
[10:51:11.012] INFO: 41600 events read in total (3312ms).
[10:51:11.018] INFO: Test took 4509ms.
[10:51:11.160] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:11.160] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 887871
[10:51:11.160] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[10:51:11.160] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:11.228] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:11.893] INFO: Expecting 41600 events.
[10:51:15.742] INFO: 41600 events read in total (3322ms).
[10:51:15.747] INFO: Test took 4520ms.
[10:51:15.891] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:15.891] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 889499
[10:51:15.891] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[10:51:15.891] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:15.958] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:16.623] INFO: Expecting 41600 events.
[10:51:20.481] INFO: 41600 events read in total (3332ms).
[10:51:20.486] INFO: Test took 4528ms.
[10:51:20.630] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:20.630] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 889164
[10:51:20.630] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[10:51:20.630] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:20.698] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:21.363] INFO: Expecting 41600 events.
[10:51:25.229] INFO: 41600 events read in total (3340ms).
[10:51:25.234] INFO: Test took 4536ms.
[10:51:25.378] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:25.378] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 891005
[10:51:25.378] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[10:51:25.379] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:25.447] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:26.112] INFO: Expecting 41600 events.
[10:51:29.979] INFO: 41600 events read in total (3340ms).
[10:51:29.985] INFO: Test took 4538ms.
[10:51:30.130] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:30.130] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 888813
[10:51:30.130] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[10:51:30.130] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:30.197] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:30.862] INFO: Expecting 41600 events.
[10:51:34.740] INFO: 41600 events read in total (3351ms).
[10:51:34.746] INFO: Test took 4549ms.
[10:51:34.893] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:34.893] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 889741
[10:51:34.893] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[10:51:34.893] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:34.961] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:35.626] INFO: Expecting 41600 events.
[10:51:39.501] INFO: 41600 events read in total (3348ms).
[10:51:39.506] INFO: Test took 4545ms.
[10:51:39.650] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:39.650] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 890099
[10:51:39.650] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[10:51:39.650] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:39.717] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:40.382] INFO: Expecting 41600 events.
[10:51:44.250] INFO: 41600 events read in total (3342ms).
[10:51:44.255] INFO: Test took 4538ms.
[10:51:44.400] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:44.400] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 889745
[10:51:44.400] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[10:51:44.400] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:44.467] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:45.132] INFO: Expecting 41600 events.
[10:51:49.004] INFO: 41600 events read in total (3346ms).
[10:51:49.010] INFO: Test took 4543ms.
[10:51:49.154] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:49.154] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 888876
[10:51:49.154] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[10:51:49.154] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:49.221] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:49.886] INFO: Expecting 41600 events.
[10:51:53.760] INFO: 41600 events read in total (3347ms).
[10:51:53.765] INFO: Test took 4544ms.
[10:51:53.911] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:53.911] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 890568
[10:51:53.911] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[10:51:53.911] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:53.979] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:54.643] INFO: Expecting 41600 events.
[10:51:58.510] INFO: 41600 events read in total (3340ms).
[10:51:58.515] INFO: Test took 4536ms.
[10:51:58.657] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:58.657] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 888588
[10:51:58.657] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[10:51:58.658] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:51:58.724] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:51:59.390] INFO: Expecting 41600 events.
[10:52:03.300] INFO: 41600 events read in total (3383ms).
[10:52:03.306] INFO: Test took 4582ms.
[10:52:03.457] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:03.457] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 889653
[10:52:03.457] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[10:52:03.457] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:03.523] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:04.201] INFO: Expecting 41600 events.
[10:52:08.077] INFO: 41600 events read in total (3349ms).
[10:52:08.082] INFO: Test took 4559ms.
[10:52:08.229] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:08.229] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 889093
[10:52:08.229] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[10:52:08.230] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:08.297] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:08.962] INFO: Expecting 41600 events.
[10:52:12.840] INFO: 41600 events read in total (3350ms).
[10:52:12.912] INFO: Test took 4615ms.
[10:52:13.069] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:13.069] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 888923
[10:52:13.069] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[10:52:13.070] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:13.144] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:13.810] INFO: Expecting 41600 events.
[10:52:17.678] INFO: 41600 events read in total (3342ms).
[10:52:17.684] INFO: Test took 4540ms.
[10:52:17.827] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:17.827] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 887342
[10:52:17.827] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[10:52:17.827] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:17.895] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:18.560] INFO: Expecting 41600 events.
[10:52:22.327] INFO: 41600 events read in total (3240ms).
[10:52:22.332] INFO: Test took 4437ms.
[10:52:22.477] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:22.477] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 885054
[10:52:22.477] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[10:52:22.477] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:22.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 152 eff = 0.99887
[10:52:22.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 172 eff = 0.999038
[10:52:22.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 178 eff = 0.997813
[10:52:22.766] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 171 eff = 0.99625
[10:52:22.766] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 165 eff = 0.995697
[10:52:22.766] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 159 eff = 0.995481
[10:52:22.766] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 170 eff = 0.996034
[10:52:22.767] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 187 eff = 0.996058
[10:52:22.767] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 156 eff = 0.995433
[10:52:22.767] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 178 eff = 0.995505
[10:52:22.767] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 175 eff = 0.995673
[10:52:22.768] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 171 eff = 0.995841
[10:52:22.768] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 162 eff = 0.995889
[10:52:22.768] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 178 eff = 0.998029
[10:52:22.768] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 140 eff = 0.999111
[10:52:22.769] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 163 eff = 0.998918
[10:52:38.069] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[10:52:38.069] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[10:52:38.069] INFO: ----------------------------------------------------------------------
[10:52:38.069] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:52:38.069] INFO: ----------------------------------------------------------------------
[10:52:38.069] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[10:52:38.069] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[10:52:38.069] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[10:52:38.069] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[10:52:38.069] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[10:52:38.069] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[10:52:38.069] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[10:52:38.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[10:52:38.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[10:52:38.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[10:52:38.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[10:52:38.070] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[10:52:38.106] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:52:38.773] INFO: Expecting 208000 events.
[10:52:55.565] INFO: 208000 events read in total (16265ms).
[10:52:55.586] INFO: Test took 17480ms.
[10:52:56.285] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:52:56.285] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4127490
[10:52:56.285] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[10:52:56.286] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:52:56.793] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:52:56.793] INFO: number of red-efficiency pixels: 234 172 406 689 860 785 686 633 766 849 876 696 707 364 192 250
[10:52:56.793] INFO: number of X-ray hits detected: 162150 117835 195149 296436 324166 318547 314083 285334 314319 334251 327487 303658 303066 198390 118862 147197
[10:52:56.793] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:52:56.793] INFO: number of Vcal hits detected: 207754 207819 207554 207233 207005 207100 207217 207273 207125 207002 206973 207194 207187 207589 207802 207736
[10:52:56.793] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.6 99.7 99.7 99.6 99.6 99.5 99.6 99.6 99.8 99.9 99.9
[10:52:56.793] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.6 99.5 99.6 99.6 99.7 99.6 99.5 99.5 99.6 99.6 99.8 99.9 99.9
[10:52:56.793] INFO: X-ray hit rate [MHz/cm2]: 47.5 34.5 57.2 86.9 95.0 93.4 92.1 83.6 92.1 98.0 96.0 89.0 88.8 58.1 34.8 43.1
[10:52:56.793] INFO: PixTestHighRate::doXPixelAlive() done
[10:52:56.838] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[10:52:56.838] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[10:52:56.838] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[10:52:56.838] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[10:52:56.838] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[10:52:56.838] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[10:52:56.838] INFO: PixTest:: pg_setup set to default.
[10:53:01.125] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[10:53:01.125] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[10:53:05.253] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:53:05.254] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 391.5mA
[10:53:05.254] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 470.3mA
[10:53:05.254] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[10:53:05.254] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:53:05.255] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:53:05.255] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:53:05.255] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:53:05.255] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:53:05.255] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:53:05.255] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:53:05.255] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:53:05.255] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:53:05.255] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:53:05.350] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:53:05.350] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:53:05.351] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:53:05.351] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:53:05.351] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:53:05.351] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:53:05.351] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:53:05.351] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:53:05.353] QUIET: Connection to board 43 closed.
[10:53:05.433] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master