Test Date: 1973-11-29 16:33
Analysis date: 2016-06-14 17:41
Logfile
hrData_40.log
[16:48:35.878] INFO: *** Welcome to pxar ***
[16:48:35.878] INFO: *** Today: 2016/06/13
[16:48:35.902] INFO: *** Version: v1.9.0-814-g7497
[16:48:35.902] INFO: readRocDacs: data/mp243/dacParameters35_C0.dat .. data/mp243/dacParameters35_C15.dat
[16:48:35.903] INFO: readTbmDacs: data/mp243/tbmParameters_C0a.dat .. data/mp243/tbmParameters_C0b.dat
[16:48:35.904] INFO: readMaskFile: data/mp243/defaultMaskFile.dat
[16:48:35.904] INFO: readTrimFile: data/mp243/trimParameters35_C0.dat .. data/mp243/trimParameters35_C15.dat
[16:48:36.055] INFO: clk: 4
[16:48:36.055] INFO: ctr: 4
[16:48:36.055] INFO: sda: 19
[16:48:36.055] INFO: tin: 9
[16:48:36.055] INFO: level: 15
[16:48:36.055] INFO: triggerdelay: 0
[16:48:36.055] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:48:36.055] INFO: Log level: DEBUG
[16:48:36.066] QUIET: Connection to board DTB_WRMNMR opened.
[16:48:36.069] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[16:48:36.072] INFO: RPC call hashes of host and DTB match: 398089610
[16:48:37.673] INFO: DUT info:
[16:48:37.673] INFO: The DUT currently contains the following objects:
[16:48:37.673] INFO: 2 TBM Cores tbm08c (2 ON)
[16:48:37.673] INFO: TBM Core alpha (0): 7 registers set
[16:48:37.673] INFO: TBM Core beta (1): 7 registers set
[16:48:37.673] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:48:37.674] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.674] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.674] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.674] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.674] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.674] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.674] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.674] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.674] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.674] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.674] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.674] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.674] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.674] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.674] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.674] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:48:37.675] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:48:37.676] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:48:37.677] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:48:37.679] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33255424
[16:48:37.680] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xec70d0
[16:48:37.680] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xc69360
[16:48:37.680] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f89c9d94010
[16:48:37.680] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f89cffff510
[16:48:37.680] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33263616 fPxarMemory = 0x7f89c9d94010
[16:48:37.681] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 382.7mA
[16:48:37.682] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 462.3mA
[16:48:37.682] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:48:38.177] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x12b1d50
[16:48:38.241] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:48:38.241] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:48:38.242] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:48:38.247] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:48:38.248] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:48:38.248] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:48:38.259] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:48:38.259] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:48:38.270] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:48:38.271] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:48:38.284] INFO: PixTestCmd::init()
[16:48:38.299] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:48:38.299] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:48:38.299] INFO: readGainPedestalParameters data/mp243/phCalibrationFitErr35_C0.dat .. data/mp243/phCalibrationFitErr35_C15.dat
[16:48:38.542] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:48:38.542] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:48:38.559] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:48:38.565] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:48:38.565] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:48:38.565] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:48:38.585] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:48:38.585] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:48:38.585] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:48:38.585] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:48:38.585] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:48:38.592] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:48:38.592] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:48:38.614] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:48:38.614] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:48:38.614] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:48:38.614] INFO: readReadbackCal: data/mp243/readbackCal_C0.dat .. data/mp243/readbackCal_C15.dat
[16:48:38.624] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:48:38.632] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:48:38.632] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:48:38.632] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:48:38.632] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:48:38.651] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:48:38.651] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:48:43.037] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[16:48:44.621] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[16:48:46.314] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[16:48:46.314] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[16:48:46.315] INFO: ----------------------------------------------------------------------
[16:48:46.315] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[16:48:46.315] INFO: ----------------------------------------------------------------------
[16:48:47.288] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[16:49:00.310] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[16:49:00.314] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:49:28.091] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1283033 events.
[16:49:33.428] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1283033, pixels seen in all events: 4855357
[16:49:33.527] INFO: Resuming triggers.
[16:49:46.548] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[16:49:46.581] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:50:14.428] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1282807 events.
[16:50:19.883] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1282807, pixels seen in all events: 4857208
[16:50:19.973] INFO: Resuming triggers.
[16:50:32.992] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[16:50:33.083] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:51:00.817] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1282589 events.
[16:51:06.175] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1282589, pixels seen in all events: 4860218
[16:51:06.335] INFO: Resuming triggers.
[16:51:19.355] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[16:51:19.393] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:51:47.177] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1282661 events.
[16:51:52.462] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1282661, pixels seen in all events: 4858991
[16:51:52.680] INFO: Resuming triggers.
[16:52:00.674] INFO: data taking finished, elapsed time: 60 seconds.
[16:52:00.870] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:52:17.888] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 787470 events.
[16:52:21.401] DEBUG: <PixTestXray.cc/processData:L823> # events read: 787470, pixels seen in all events: 2985634
[16:52:21.473] INFO: PixTest:: pg_setup set to default.
[16:52:21.546] INFO: PixTestXray::doPhRun() done
[16:52:31.914] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:52:31.915] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.1mA
[16:52:31.916] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 463.1mA
[16:52:31.916] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:52:31.916] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:52:31.916] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:52:31.916] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:52:31.916] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:52:31.916] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:52:31.917] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:52:31.917] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:52:31.917] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:52:31.917] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:52:31.917] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:52:31.917] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:52:31.917] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:52:31.917] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:52:31.917] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:52:31.917] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:52:31.917] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:52:31.917] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:52:31.917] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:52:32.184] QUIET: Connection to board 43 closed.
[16:52:32.264] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[16:52:38.559] INFO: *** Welcome to pxar ***
[16:52:38.559] INFO: *** Today: 2016/06/13
[16:52:38.583] INFO: *** Version: v1.9.0-814-g7497
[16:52:38.583] INFO: readRocDacs: data/mp243/dacParameters35_C0.dat .. data/mp243/dacParameters35_C15.dat
[16:52:38.584] INFO: readTbmDacs: data/mp243/tbmParameters_C0a.dat .. data/mp243/tbmParameters_C0b.dat
[16:52:38.585] INFO: readMaskFile: data/mp243/defaultMaskFile.dat
[16:52:38.585] INFO: readTrimFile: data/mp243/trimParameters35_C0.dat .. data/mp243/trimParameters35_C15.dat
[16:52:38.735] INFO: clk: 4
[16:52:38.735] INFO: ctr: 4
[16:52:38.735] INFO: sda: 19
[16:52:38.735] INFO: tin: 9
[16:52:38.735] INFO: level: 15
[16:52:38.735] INFO: triggerdelay: 0
[16:52:38.735] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:52:38.735] INFO: Log level: DEBUG
[16:52:38.746] QUIET: Connection to board DTB_WRMNMR opened.
[16:52:38.749] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[16:52:38.752] INFO: RPC call hashes of host and DTB match: 398089610
[16:52:40.354] INFO: DUT info:
[16:52:40.354] INFO: The DUT currently contains the following objects:
[16:52:40.354] INFO: 2 TBM Cores tbm08c (2 ON)
[16:52:40.354] INFO: TBM Core alpha (0): 7 registers set
[16:52:40.354] INFO: TBM Core beta (1): 7 registers set
[16:52:40.354] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:52:40.354] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.354] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.354] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.354] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.354] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.354] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.354] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.354] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.354] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.354] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.354] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.354] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.354] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.354] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.355] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.355] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:52:40.355] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:52:40.356] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:52:40.357] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:52:40.358] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:52:40.360] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33263616
[16:52:40.360] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xd89bd0
[16:52:40.360] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xb2be60
[16:52:40.360] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f1381d94010
[16:52:40.360] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f1387fff510
[16:52:40.360] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33271808 fPxarMemory = 0x7f1381d94010
[16:52:40.362] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 382.7mA
[16:52:40.363] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 462.3mA
[16:52:40.363] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:52:40.855] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1174790
[16:52:40.920] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:52:40.921] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:52:40.921] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:52:40.926] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:52:40.926] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:52:40.926] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:52:40.937] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:52:40.937] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:52:40.958] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:52:40.958] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:52:40.973] INFO: PixTestCmd::init()
[16:52:40.988] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:52:40.988] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:52:40.988] INFO: readGainPedestalParameters data/mp243/phCalibrationFitErr35_C0.dat .. data/mp243/phCalibrationFitErr35_C15.dat
[16:52:41.231] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:52:41.231] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:52:41.244] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:52:41.250] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:52:41.250] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:52:41.250] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:52:41.276] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:52:41.276] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:52:41.276] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:52:41.276] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:52:41.276] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:52:41.282] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:52:41.282] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:52:41.301] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:52:41.301] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:52:41.302] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:52:41.302] INFO: readReadbackCal: data/mp243/readbackCal_C0.dat .. data/mp243/readbackCal_C15.dat
[16:52:41.311] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:52:41.319] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:52:41.319] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:52:41.319] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:52:41.319] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:52:41.336] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:52:41.336] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:52:48.333] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[16:52:50.189] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[16:52:52.482] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[16:52:52.482] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[16:52:52.483] INFO: ----------------------------------------------------------------------
[16:52:52.483] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[16:52:52.483] INFO: ----------------------------------------------------------------------
[16:52:53.456] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[16:53:01.215] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[16:53:01.219] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:53:26.359] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 764531 events.
[16:53:38.269] DEBUG: <PixTestXray.cc/processData:L823> # events read: 764531, pixels seen in all events: 11078739
[16:53:38.519] INFO: Resuming triggers.
[16:53:46.277] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[16:53:46.315] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:54:11.388] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 764269 events.
[16:54:23.346] DEBUG: <PixTestXray.cc/processData:L823> # events read: 764269, pixels seen in all events: 11080175
[16:54:23.512] INFO: Resuming triggers.
[16:54:31.268] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[16:54:31.308] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:54:56.429] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 764012 events.
[16:55:08.382] DEBUG: <PixTestXray.cc/processData:L823> # events read: 764012, pixels seen in all events: 11083740
[16:55:08.617] INFO: Resuming triggers.
[16:55:16.378] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[16:55:16.414] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:55:41.449] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 764626 events.
[16:55:53.390] DEBUG: <PixTestXray.cc/processData:L823> # events read: 764626, pixels seen in all events: 11076333
[16:55:53.622] INFO: Resuming triggers.
[16:56:01.382] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[16:56:01.418] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:56:26.462] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 764431 events.
[16:56:38.406] DEBUG: <PixTestXray.cc/processData:L823> # events read: 764431, pixels seen in all events: 11079249
[16:56:38.643] INFO: Resuming triggers.
[16:56:46.406] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[16:56:46.443] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:57:11.636] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 764762 events.
[16:57:23.602] DEBUG: <PixTestXray.cc/processData:L823> # events read: 764762, pixels seen in all events: 11074102
[16:57:23.805] INFO: Resuming triggers.
[16:57:31.568] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[16:57:31.637] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:57:56.549] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 764790 events.
[16:58:08.539] DEBUG: <PixTestXray.cc/processData:L823> # events read: 764790, pixels seen in all events: 11074774
[16:58:08.778] INFO: Resuming triggers.
[16:58:14.539] INFO: data taking finished, elapsed time: 60 seconds.
[16:58:14.735] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:58:33.506] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 567488 events.
[16:58:42.398] DEBUG: <PixTestXray.cc/processData:L823> # events read: 567488, pixels seen in all events: 8221468
[16:58:42.583] INFO: PixTest:: pg_setup set to default.
[16:58:42.628] INFO: PixTestXray::doPhRun() done
[16:59:06.779] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:59:06.779] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.9mA
[16:59:06.780] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 463.1mA
[16:59:06.780] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[16:59:06.780] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:59:06.780] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:59:06.780] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:59:06.780] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:59:06.780] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:59:06.781] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:59:06.781] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:59:06.781] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:59:06.781] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:59:06.781] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:59:06.781] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:59:06.781] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:59:06.781] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:59:06.781] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:59:06.781] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:59:06.781] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:59:06.781] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:59:06.781] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:59:07.089] QUIET: Connection to board 43 closed.
[16:59:07.169] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[16:44:03.979] INFO: *** Welcome to pxar ***
[16:44:03.979] INFO: *** Today: 2016/06/13
[16:44:04.005] INFO: *** Version: v1.9.0-814-g7497
[16:44:04.005] INFO: readRocDacs: data/mp243/dacParameters35_C0.dat .. data/mp243/dacParameters35_C15.dat
[16:44:04.006] INFO: readTbmDacs: data/mp243/tbmParameters_C0a.dat .. data/mp243/tbmParameters_C0b.dat
[16:44:04.006] INFO: readMaskFile: data/mp243/defaultMaskFile.dat
[16:44:04.006] INFO: readTrimFile: data/mp243/trimParameters35_C0.dat .. data/mp243/trimParameters35_C15.dat
[16:44:04.155] INFO: clk: 4
[16:44:04.155] INFO: ctr: 4
[16:44:04.155] INFO: sda: 19
[16:44:04.155] INFO: tin: 9
[16:44:04.155] INFO: level: 15
[16:44:04.155] INFO: triggerdelay: 0
[16:44:04.155] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:44:04.155] INFO: Log level: DEBUG
[16:44:04.167] QUIET: Connection to board DTB_WRMNMR opened.
[16:44:04.171] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[16:44:04.173] INFO: RPC call hashes of host and DTB match: 398089610
[16:44:05.774] INFO: DUT info:
[16:44:05.774] INFO: The DUT currently contains the following objects:
[16:44:05.774] INFO: 2 TBM Cores tbm08c (2 ON)
[16:44:05.774] INFO: TBM Core alpha (0): 7 registers set
[16:44:05.775] INFO: TBM Core beta (1): 7 registers set
[16:44:05.775] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:44:05.775] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:44:05.775] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:44:05.775] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:44:05.775] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:44:05.775] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:44:05.775] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:44:05.775] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:44:05.776] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:44:05.777] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:44:05.778] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:44:05.779] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:44:05.779] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:44:05.779] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:44:05.781] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35758080
[16:44:05.781] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x19750d0
[16:44:05.781] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1717360
[16:44:05.781] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f9d95393010
[16:44:05.781] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f9d9b5fe510
[16:44:05.781] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35766272 fPxarMemory = 0x7f9d95393010
[16:44:05.782] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 382.7mA
[16:44:05.783] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 462.3mA
[16:44:05.783] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[16:44:06.281] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1d5fdb0
[16:44:06.344] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:44:06.344] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:44:06.344] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:44:06.350] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:44:06.350] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:44:06.350] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:44:06.363] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:44:06.363] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:44:06.386] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:44:06.386] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:44:06.404] INFO: PixTestCmd::init()
[16:44:06.413] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:44:06.413] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:44:06.413] INFO: readGainPedestalParameters data/mp243/phCalibrationFitErr35_C0.dat .. data/mp243/phCalibrationFitErr35_C15.dat
[16:44:06.657] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:44:06.657] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:44:06.675] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:44:06.681] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:44:06.681] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:44:06.681] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:44:06.704] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:44:06.704] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:44:06.704] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:44:06.704] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:44:06.704] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:44:06.710] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:44:06.710] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:44:06.730] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:44:06.730] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:44:06.730] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:44:06.730] INFO: readReadbackCal: data/mp243/readbackCal_C0.dat .. data/mp243/readbackCal_C15.dat
[16:44:06.739] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:44:06.747] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:44:06.747] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:44:06.747] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:44:06.747] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:44:06.764] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:44:06.764] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:44:11.318] INFO: ######################################################################
[16:44:11.318] INFO: PixTestAlive::doTest()
[16:44:11.318] INFO: ######################################################################
[16:44:11.321] INFO: ----------------------------------------------------------------------
[16:44:11.321] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:44:11.321] INFO: ----------------------------------------------------------------------
[16:44:11.324] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:11.704] INFO: Expecting 41600 events.
[16:44:15.882] INFO: 41600 events read in total (3460ms).
[16:44:16.029] INFO: Test took 4705ms.
[16:44:16.040] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:16.040] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[16:44:16.041] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:44:16.314] INFO: PixTestAlive::aliveTest() done
[16:44:16.314] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:44:16.314] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 18 11 23 26 36 40 34 35 28 31 40 44 31 31 10 10
[16:44:16.338] INFO: ----------------------------------------------------------------------
[16:44:16.338] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:44:16.338] INFO: ----------------------------------------------------------------------
[16:44:16.340] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:16.718] INFO: Expecting 41600 events.
[16:44:19.626] INFO: 41600 events read in total (2192ms).
[16:44:19.626] INFO: Test took 3286ms.
[16:44:19.626] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:19.626] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:44:19.626] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:44:19.627] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:44:20.049] INFO: PixTestAlive::maskTest() done
[16:44:20.049] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:44:20.071] INFO: ----------------------------------------------------------------------
[16:44:20.071] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:44:20.071] INFO: ----------------------------------------------------------------------
[16:44:20.073] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:20.454] INFO: Expecting 41600 events.
[16:44:24.620] INFO: 41600 events read in total (3451ms).
[16:44:24.622] INFO: Test took 4549ms.
[16:44:24.633] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:24.633] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[16:44:24.633] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:44:25.003] INFO: PixTestAlive::addressDecodingTest() done
[16:44:25.003] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:44:25.003] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[16:44:26.650] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:44:26.650] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:44:26.650] INFO: ----------------------------------------------------------------------
[16:44:26.650] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:44:26.650] INFO: ----------------------------------------------------------------------
[16:44:26.791] INFO: Expecting 768 events.
[16:44:27.921] INFO: 768 events read in total (415ms).
[16:44:27.921] INFO: Test took 1264ms.
[16:44:27.928] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:28.724] INFO: Expecting 41600 events.
[16:44:31.903] INFO: 41600 events read in total (2652ms).
[16:44:31.908] INFO: Test took 3980ms.
[16:44:31.984] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:31.984] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 442201
[16:44:31.984] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:44:31.984] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:32.020] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:32.685] INFO: Expecting 41600 events.
[16:44:35.988] INFO: 41600 events read in total (2776ms).
[16:44:35.991] INFO: Test took 3971ms.
[16:44:36.065] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:36.065] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 450503
[16:44:36.065] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:44:36.066] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:36.103] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:36.768] INFO: Expecting 41600 events.
[16:44:40.080] INFO: 41600 events read in total (2785ms).
[16:44:40.083] INFO: Test took 3980ms.
[16:44:40.157] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:40.157] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 451613
[16:44:40.157] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:44:40.158] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:40.195] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:40.860] INFO: Expecting 41600 events.
[16:44:44.191] INFO: 41600 events read in total (2804ms).
[16:44:44.194] INFO: Test took 3999ms.
[16:44:44.269] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:44.270] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 451927
[16:44:44.270] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:44:44.270] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:44.308] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:44.972] INFO: Expecting 41600 events.
[16:44:48.301] INFO: 41600 events read in total (2802ms).
[16:44:48.304] INFO: Test took 3996ms.
[16:44:48.379] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:48.379] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 453660
[16:44:48.379] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:44:48.379] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:48.417] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:49.082] INFO: Expecting 41600 events.
[16:44:52.419] INFO: 41600 events read in total (2811ms).
[16:44:52.423] INFO: Test took 4006ms.
[16:44:52.497] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:52.497] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 453416
[16:44:52.497] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:44:52.497] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:52.535] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:53.200] INFO: Expecting 41600 events.
[16:44:56.545] INFO: 41600 events read in total (2819ms).
[16:44:56.549] INFO: Test took 4014ms.
[16:44:56.624] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:56.624] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452714
[16:44:56.624] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:44:56.624] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:56.662] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:57.327] INFO: Expecting 41600 events.
[16:45:00.669] INFO: 41600 events read in total (2816ms).
[16:45:00.672] INFO: Test took 4010ms.
[16:45:00.746] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:00.746] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452202
[16:45:00.747] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:45:00.747] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:45:00.785] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:45:01.450] INFO: Expecting 41600 events.
[16:45:04.790] INFO: 41600 events read in total (2814ms).
[16:45:04.793] INFO: Test took 4008ms.
[16:45:04.867] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:04.867] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452678
[16:45:04.867] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:45:04.867] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:45:04.905] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:45:05.570] INFO: Expecting 41600 events.
[16:45:08.923] INFO: 41600 events read in total (2826ms).
[16:45:08.926] INFO: Test took 4021ms.
[16:45:09.001] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:09.001] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 453667
[16:45:09.001] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:45:09.002] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:45:09.040] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:45:09.705] INFO: Expecting 41600 events.
[16:45:13.042] INFO: 41600 events read in total (2810ms).
[16:45:13.046] INFO: Test took 4006ms.
[16:45:13.120] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:13.120] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452987
[16:45:13.120] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:45:13.121] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:45:13.159] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:45:13.824] INFO: Expecting 41600 events.
[16:45:17.179] INFO: 41600 events read in total (2828ms).
[16:45:17.183] INFO: Test took 4024ms.
[16:45:17.257] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:17.257] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452411
[16:45:17.257] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:45:17.257] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:45:17.295] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:45:17.960] INFO: Expecting 41600 events.
[16:45:21.304] INFO: 41600 events read in total (2817ms).
[16:45:21.307] INFO: Test took 4012ms.
[16:45:21.382] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:21.382] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452542
[16:45:21.382] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:45:21.382] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:45:21.421] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:45:22.086] INFO: Expecting 41600 events.
[16:45:25.436] INFO: 41600 events read in total (2823ms).
[16:45:25.440] INFO: Test took 4019ms.
[16:45:25.515] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:25.515] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 453203
[16:45:25.515] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:45:25.516] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:45:25.554] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:45:26.219] INFO: Expecting 41600 events.
[16:45:29.558] INFO: 41600 events read in total (2812ms).
[16:45:29.562] INFO: Test took 4008ms.
[16:45:29.636] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:29.636] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 453202
[16:45:29.636] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:45:29.636] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:45:29.675] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:45:30.340] INFO: Expecting 41600 events.
[16:45:33.680] INFO: 41600 events read in total (2814ms).
[16:45:33.683] INFO: Test took 4008ms.
[16:45:33.759] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:33.759] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452723
[16:45:33.759] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:45:33.759] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:45:33.797] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:45:34.462] INFO: Expecting 41600 events.
[16:45:37.806] INFO: 41600 events read in total (2817ms).
[16:45:37.809] INFO: Test took 4012ms.
[16:45:37.884] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:37.884] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 452591
[16:45:37.884] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:45:37.885] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:45:37.923] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:45:38.589] INFO: Expecting 41600 events.
[16:45:41.928] INFO: 41600 events read in total (2813ms).
[16:45:41.931] INFO: Test took 4008ms.
[16:45:42.006] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:42.006] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 453147
[16:45:42.006] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:45:42.007] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:45:42.045] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:45:42.709] INFO: Expecting 41600 events.
[16:45:46.054] INFO: 41600 events read in total (2818ms).
[16:45:46.058] INFO: Test took 4013ms.
[16:45:46.133] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:46.133] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 451625
[16:45:46.133] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:45:46.133] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:45:46.172] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:45:46.837] INFO: Expecting 41600 events.
[16:45:50.010] INFO: 41600 events read in total (2646ms).
[16:45:50.013] INFO: Test took 3841ms.
[16:45:50.087] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:50.087] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 446409
[16:45:50.087] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:45:50.088] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:45:50.347] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 149 eff = 0.999375
[16:45:50.348] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 141 eff = 0.999832
[16:45:50.348] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 162 eff = 0.999567
[16:45:50.348] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 151 eff = 0.999111
[16:45:50.348] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 153 eff = 0.99899
[16:45:50.349] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 149 eff = 0.999135
[16:45:50.349] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 175 eff = 0.999207
[16:45:50.349] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 163 eff = 0.999038
[16:45:50.349] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 163 eff = 0.998966
[16:45:50.349] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 182 eff = 0.999014
[16:45:50.350] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 177 eff = 0.998966
[16:45:50.350] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 165 eff = 0.999375
[16:45:50.350] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 155 eff = 0.998966
[16:45:50.350] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 167 eff = 0.999471
[16:45:50.351] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 152 eff = 0.999736
[16:45:50.351] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 162 eff = 0.999663
[16:45:53.562] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:45:53.562] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:45:53.562] INFO: ----------------------------------------------------------------------
[16:45:53.562] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:45:53.562] INFO: ----------------------------------------------------------------------
[16:45:53.563] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:45:53.563] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:45:53.563] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:45:53.563] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:45:53.563] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:45:53.563] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:45:53.563] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:45:53.563] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:45:53.563] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:45:53.563] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:45:53.563] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:45:53.563] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:45:53.582] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:45:54.253] INFO: Expecting 208000 events.
[16:46:07.510] INFO: 208000 events read in total (12730ms).
[16:46:07.521] INFO: Test took 13939ms.
[16:46:07.855] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:46:07.855] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1983288
[16:46:07.855] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:46:07.855] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:46:08.216] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:46:08.216] INFO: number of red-efficiency pixels: 104 78 142 253 205 246 207 188 268 239 228 254 195 145 65 93
[16:46:08.216] INFO: number of X-ray hits detected: 75119 52214 91871 143268 148619 150321 145177 137291 148100 152425 154719 146133 145964 95884 56684 72940
[16:46:08.216] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:46:08.216] INFO: number of Vcal hits detected: 207846 207921 207854 207741 207786 207746 207789 207803 207721 207754 207769 207740 207800 207848 207933 207906
[16:46:08.216] 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
[16:46:08.216] 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
[16:46:08.216] INFO: X-ray hit rate [MHz/cm2]: 22.0 15.3 26.9 42.0 43.6 44.1 42.6 40.2 43.4 44.7 45.3 42.8 42.8 28.1 16.6 21.4
[16:46:08.216] INFO: PixTestHighRate::doXPixelAlive() done
[16:46:08.262] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:46:08.262] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:46:08.262] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:46:08.263] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:46:08.263] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:46:08.263] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:46:08.263] INFO: PixTest:: pg_setup set to default.
[16:46:12.866] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:46:12.866] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[16:46:14.786] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:46:14.787] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.1mA
[16:46:14.788] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 462.3mA
[16:46:14.788] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[16:46:14.788] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:46:14.788] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:46:14.788] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:46:14.788] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:46:14.788] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:46:14.789] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:46:14.789] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:46:14.789] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:46:14.789] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:46:14.789] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:46:14.882] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:46:14.882] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:46:14.891] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:46:14.891] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:46:14.891] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:46:14.891] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:46:14.891] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:46:14.891] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:46:14.894] QUIET: Connection to board 43 closed.
[16:46:14.973] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[16:41:21.865] INFO: *** Welcome to pxar ***
[16:41:21.865] INFO: *** Today: 2016/06/13
[16:41:21.893] INFO: *** Version: v1.9.0-814-g7497
[16:41:21.894] INFO: readRocDacs: data/mp243/dacParameters35_C0.dat .. data/mp243/dacParameters35_C15.dat
[16:41:21.895] INFO: readTbmDacs: data/mp243/tbmParameters_C0a.dat .. data/mp243/tbmParameters_C0b.dat
[16:41:21.895] INFO: readMaskFile: data/mp243/defaultMaskFile.dat
[16:41:21.895] INFO: readTrimFile: data/mp243/trimParameters35_C0.dat .. data/mp243/trimParameters35_C15.dat
[16:41:22.046] INFO: clk: 4
[16:41:22.046] INFO: ctr: 4
[16:41:22.046] INFO: sda: 19
[16:41:22.046] INFO: tin: 9
[16:41:22.046] INFO: level: 15
[16:41:22.046] INFO: triggerdelay: 0
[16:41:22.046] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:41:22.046] INFO: Log level: DEBUG
[16:41:22.058] QUIET: Connection to board DTB_WRMNMR opened.
[16:41:22.061] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[16:41:22.064] INFO: RPC call hashes of host and DTB match: 398089610
[16:41:23.665] INFO: DUT info:
[16:41:23.665] INFO: The DUT currently contains the following objects:
[16:41:23.665] INFO: 2 TBM Cores tbm08c (2 ON)
[16:41:23.665] INFO: TBM Core alpha (0): 7 registers set
[16:41:23.665] INFO: TBM Core beta (1): 7 registers set
[16:41:23.665] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:41:23.665] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.665] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.665] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.665] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.665] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.665] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.665] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.665] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.666] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.666] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.666] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.666] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.666] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.666] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.666] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.666] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:41:23.666] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:41:23.667] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:41:23.668] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:41:23.669] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:41:23.671] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35336192
[16:41:23.671] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x23610d0
[16:41:23.671] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2103360
[16:41:23.671] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f72fdd94010
[16:41:23.671] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f7303fff510
[16:41:23.671] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35344384 fPxarMemory = 0x7f72fdd94010
[16:41:23.673] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 382.7mA
[16:41:23.674] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 462.3mA
[16:41:23.674] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[16:41:24.173] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x274bd50
[16:41:24.236] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:41:24.236] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:41:24.237] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:41:24.244] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:41:24.244] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:41:24.244] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:41:24.257] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:41:24.257] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:41:24.267] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:41:24.267] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:41:24.284] INFO: PixTestCmd::init()
[16:41:24.298] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:41:24.298] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:41:24.298] INFO: readGainPedestalParameters data/mp243/phCalibrationFitErr35_C0.dat .. data/mp243/phCalibrationFitErr35_C15.dat
[16:41:24.540] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:41:24.541] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:41:24.556] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:41:24.562] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:41:24.562] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:41:24.562] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:41:24.582] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:41:24.582] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:41:24.582] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:41:24.582] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:41:24.582] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:41:24.588] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:41:24.588] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:41:24.606] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:41:24.606] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:41:24.606] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:41:24.606] INFO: readReadbackCal: data/mp243/readbackCal_C0.dat .. data/mp243/readbackCal_C15.dat
[16:41:24.615] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:41:24.623] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:41:24.623] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:41:24.623] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:41:24.623] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:41:24.642] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:41:24.642] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:41:28.749] INFO: ######################################################################
[16:41:28.749] INFO: PixTestAlive::doTest()
[16:41:28.749] INFO: ######################################################################
[16:41:28.753] INFO: ----------------------------------------------------------------------
[16:41:28.753] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:41:28.753] INFO: ----------------------------------------------------------------------
[16:41:28.755] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:41:29.133] INFO: Expecting 41600 events.
[16:41:33.337] INFO: 41600 events read in total (3485ms).
[16:41:33.485] INFO: Test took 4730ms.
[16:41:33.497] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:33.497] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[16:41:33.497] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:41:33.767] INFO: PixTestAlive::aliveTest() done
[16:41:33.767] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:41:33.767] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 26 18 32 69 49 54 59 53 41 63 57 64 66 37 24 29
[16:41:33.792] INFO: ----------------------------------------------------------------------
[16:41:33.792] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:41:33.792] INFO: ----------------------------------------------------------------------
[16:41:33.795] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:41:34.172] INFO: Expecting 41600 events.
[16:41:37.113] INFO: 41600 events read in total (2226ms).
[16:41:37.113] INFO: Test took 3318ms.
[16:41:37.113] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:37.113] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:41:37.113] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:41:37.114] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:41:37.536] INFO: PixTestAlive::maskTest() done
[16:41:37.536] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:41:37.559] INFO: ----------------------------------------------------------------------
[16:41:37.559] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:41:37.559] INFO: ----------------------------------------------------------------------
[16:41:37.561] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:41:37.939] INFO: Expecting 41600 events.
[16:41:42.130] INFO: 41600 events read in total (3476ms).
[16:41:42.131] INFO: Test took 4570ms.
[16:41:42.143] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:42.143] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[16:41:42.143] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:41:42.512] INFO: PixTestAlive::addressDecodingTest() done
[16:41:42.513] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:41:42.513] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[16:41:45.434] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:41:45.434] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:41:45.434] INFO: ----------------------------------------------------------------------
[16:41:45.434] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:41:45.434] INFO: ----------------------------------------------------------------------
[16:41:45.574] INFO: Expecting 768 events.
[16:41:46.702] INFO: 768 events read in total (413ms).
[16:41:46.702] INFO: Test took 1262ms.
[16:41:46.708] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:41:47.505] INFO: Expecting 41600 events.
[16:41:50.979] INFO: 41600 events read in total (2947ms).
[16:41:50.984] INFO: Test took 4276ms.
[16:41:51.105] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:51.105] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685033
[16:41:51.105] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:41:51.106] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:41:51.157] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:41:51.822] INFO: Expecting 41600 events.
[16:41:55.400] INFO: 41600 events read in total (3051ms).
[16:41:55.404] INFO: Test took 4247ms.
[16:41:55.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:55.520] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 693336
[16:41:55.520] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:41:55.520] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:41:55.574] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:41:56.239] INFO: Expecting 41600 events.
[16:41:59.837] INFO: 41600 events read in total (3071ms).
[16:41:59.842] INFO: Test took 4268ms.
[16:41:59.957] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:59.957] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 695043
[16:41:59.957] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:41:59.957] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:42:00.012] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:00.677] INFO: Expecting 41600 events.
[16:42:04.275] INFO: 41600 events read in total (3071ms).
[16:42:04.279] INFO: Test took 4267ms.
[16:42:04.397] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:04.397] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 694022
[16:42:04.397] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:42:04.397] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:42:04.451] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:05.117] INFO: Expecting 41600 events.
[16:42:08.738] INFO: 41600 events read in total (3095ms).
[16:42:08.742] INFO: Test took 4291ms.
[16:42:08.859] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:08.859] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 695970
[16:42:08.859] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:42:08.859] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:42:08.914] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:09.579] INFO: Expecting 41600 events.
[16:42:13.208] INFO: 41600 events read in total (3103ms).
[16:42:13.213] INFO: Test took 4299ms.
[16:42:13.375] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:13.376] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696384
[16:42:13.376] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:42:13.376] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:42:13.431] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:14.096] INFO: Expecting 41600 events.
[16:42:17.722] INFO: 41600 events read in total (3100ms).
[16:42:17.727] INFO: Test took 4296ms.
[16:42:17.845] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:17.845] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696929
[16:42:17.845] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:42:17.845] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:42:17.900] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:18.565] INFO: Expecting 41600 events.
[16:42:22.185] INFO: 41600 events read in total (3094ms).
[16:42:22.189] INFO: Test took 4289ms.
[16:42:22.306] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:22.306] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 694837
[16:42:22.306] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:42:22.306] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:42:22.360] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:23.025] INFO: Expecting 41600 events.
[16:42:26.654] INFO: 41600 events read in total (3102ms).
[16:42:26.659] INFO: Test took 4299ms.
[16:42:26.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:26.776] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696855
[16:42:26.776] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:42:26.776] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:42:26.830] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:27.495] INFO: Expecting 41600 events.
[16:42:31.122] INFO: 41600 events read in total (3100ms).
[16:42:31.127] INFO: Test took 4297ms.
[16:42:31.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:31.244] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696817
[16:42:31.245] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:42:31.245] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:42:31.304] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:31.970] INFO: Expecting 41600 events.
[16:42:35.583] INFO: 41600 events read in total (3087ms).
[16:42:35.588] INFO: Test took 4284ms.
[16:42:35.706] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:35.706] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 695038
[16:42:35.706] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:42:35.706] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:42:35.762] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:36.427] INFO: Expecting 41600 events.
[16:42:40.056] INFO: 41600 events read in total (3102ms).
[16:42:40.060] INFO: Test took 4298ms.
[16:42:40.177] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:40.177] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696706
[16:42:40.177] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:42:40.177] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:42:40.233] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:40.903] INFO: Expecting 41600 events.
[16:42:44.533] INFO: 41600 events read in total (3104ms).
[16:42:44.537] INFO: Test took 4304ms.
[16:42:44.660] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:44.660] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696005
[16:42:44.660] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:42:44.660] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:42:44.715] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:45.384] INFO: Expecting 41600 events.
[16:42:49.020] INFO: 41600 events read in total (3110ms).
[16:42:49.025] INFO: Test took 4310ms.
[16:42:49.142] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:49.142] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 695924
[16:42:49.142] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:42:49.143] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:42:49.197] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:49.863] INFO: Expecting 41600 events.
[16:42:53.496] INFO: 41600 events read in total (3107ms).
[16:42:53.500] INFO: Test took 4302ms.
[16:42:53.616] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:53.616] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 696148
[16:42:53.616] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:42:53.616] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:42:53.672] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:54.337] INFO: Expecting 41600 events.
[16:42:57.972] INFO: 41600 events read in total (3108ms).
[16:42:57.976] INFO: Test took 4304ms.
[16:42:58.093] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:58.093] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 695860
[16:42:58.093] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:42:58.093] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:42:58.148] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:58.813] INFO: Expecting 41600 events.
[16:43:02.446] INFO: 41600 events read in total (3107ms).
[16:43:02.451] INFO: Test took 4303ms.
[16:43:02.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:02.568] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 695862
[16:43:02.568] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:43:02.568] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:43:02.623] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:43:03.287] INFO: Expecting 41600 events.
[16:43:06.922] INFO: 41600 events read in total (3108ms).
[16:43:06.926] INFO: Test took 4303ms.
[16:43:07.045] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:07.045] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 695666
[16:43:07.045] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:43:07.045] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:43:07.100] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:43:07.765] INFO: Expecting 41600 events.
[16:43:11.375] INFO: 41600 events read in total (3083ms).
[16:43:11.380] INFO: Test took 4280ms.
[16:43:11.496] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:11.496] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 694918
[16:43:11.496] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:43:11.497] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:43:11.551] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:43:12.216] INFO: Expecting 41600 events.
[16:43:15.664] INFO: 41600 events read in total (2921ms).
[16:43:15.669] INFO: Test took 4118ms.
[16:43:15.783] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:15.783] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688174
[16:43:15.783] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:43:15.783] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:43:16.059] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 172 eff = 0.999183
[16:43:16.059] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 157 eff = 0.999567
[16:43:16.059] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 181 eff = 0.99899
[16:43:16.059] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 165 eff = 0.998077
[16:43:16.059] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 150 eff = 0.997572
[16:43:16.059] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 159 eff = 0.998125
[16:43:16.060] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 174 eff = 0.997981
[16:43:16.060] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 164 eff = 0.997837
[16:43:16.060] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 177 eff = 0.997885
[16:43:16.060] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 179 eff = 0.997885
[16:43:16.060] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 179 eff = 0.99762
[16:43:16.060] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 184 eff = 0.997813
[16:43:16.060] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 151 eff = 0.997548
[16:43:16.061] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 165 eff = 0.99875
[16:43:16.061] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 129 eff = 0.999615
[16:43:16.061] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 171 eff = 0.999399
[16:43:18.818] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:43:18.818] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:43:18.818] INFO: ----------------------------------------------------------------------
[16:43:18.818] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:43:18.818] INFO: ----------------------------------------------------------------------
[16:43:18.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:43:18.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:43:18.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:43:18.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:43:18.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:43:18.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:43:18.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:43:18.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:43:18.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:43:18.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:43:18.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:43:18.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:43:18.837] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:43:19.504] INFO: Expecting 208000 events.
[16:43:34.638] INFO: 208000 events read in total (14607ms).
[16:43:34.655] INFO: Test took 15818ms.
[16:43:35.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:35.283] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3184582
[16:43:35.283] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:43:35.284] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:43:35.726] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:43:35.726] INFO: number of red-efficiency pixels: 162 116 236 455 491 474 425 407 435 441 514 442 435 273 167 174
[16:43:35.726] INFO: number of X-ray hits detected: 122135 85154 149288 232252 240321 245854 236674 222945 240885 247629 251195 237735 238262 156647 92326 118721
[16:43:35.726] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:43:35.726] INFO: number of Vcal hits detected: 207784 207881 207757 207517 207467 207485 207552 207561 207529 207524 207423 207521 207527 207705 207826 207822
[16:43:35.726] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.7 99.8 99.8 99.9 99.9 99.9
[16:43:35.726] INFO: Vcal hit overall 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
[16:43:35.726] INFO: X-ray hit rate [MHz/cm2]: 35.8 25.0 43.8 68.1 70.4 72.1 69.4 65.3 70.6 72.6 73.6 69.7 69.8 45.9 27.1 34.8
[16:43:35.726] INFO: PixTestHighRate::doXPixelAlive() done
[16:43:35.772] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:43:35.772] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:43:35.772] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:43:35.772] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:43:35.772] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:43:35.772] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:43:35.772] INFO: PixTest:: pg_setup set to default.
[16:43:43.266] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:43:43.266] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[16:43:58.426] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:43:58.427] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.1mA
[16:43:58.428] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 462.3mA
[16:43:58.428] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:43:58.428] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:43:58.428] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:43:58.428] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:43:58.428] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:43:58.428] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:43:58.428] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:43:58.428] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:43:58.428] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:43:58.428] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:43:58.428] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:43:58.520] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:43:58.520] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:43:58.530] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:43:58.530] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:43:58.530] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:43:58.530] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:43:58.530] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:43:58.530] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:43:58.533] QUIET: Connection to board 43 closed.
[16:43:58.612] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[16:38:37.019] INFO: *** Welcome to pxar ***
[16:38:37.019] INFO: *** Today: 2016/06/13
[16:38:37.043] INFO: *** Version: v1.9.0-814-g7497
[16:38:37.043] INFO: readRocDacs: data/mp243/dacParameters35_C0.dat .. data/mp243/dacParameters35_C15.dat
[16:38:37.044] INFO: readTbmDacs: data/mp243/tbmParameters_C0a.dat .. data/mp243/tbmParameters_C0b.dat
[16:38:37.045] INFO: readMaskFile: data/mp243/defaultMaskFile.dat
[16:38:37.045] INFO: readTrimFile: data/mp243/trimParameters35_C0.dat .. data/mp243/trimParameters35_C15.dat
[16:38:37.195] INFO: clk: 4
[16:38:37.195] INFO: ctr: 4
[16:38:37.195] INFO: sda: 19
[16:38:37.195] INFO: tin: 9
[16:38:37.195] INFO: level: 15
[16:38:37.195] INFO: triggerdelay: 0
[16:38:37.195] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:38:37.195] INFO: Log level: DEBUG
[16:38:37.206] QUIET: Connection to board DTB_WRMNMR opened.
[16:38:37.209] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[16:38:37.212] INFO: RPC call hashes of host and DTB match: 398089610
[16:38:38.814] INFO: DUT info:
[16:38:38.814] INFO: The DUT currently contains the following objects:
[16:38:38.814] INFO: 2 TBM Cores tbm08c (2 ON)
[16:38:38.814] INFO: TBM Core alpha (0): 7 registers set
[16:38:38.814] INFO: TBM Core beta (1): 7 registers set
[16:38:38.814] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:38:38.814] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.814] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.814] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.814] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.814] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.814] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.814] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.814] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.814] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.814] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.814] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.814] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.814] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.814] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.814] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.814] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:38:38.815] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:38:38.816] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:38:38.817] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:38:38.818] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:38:38.818] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:38:38.818] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:38:38.818] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:38:38.818] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:38:38.818] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:38:38.820] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32759808
[16:38:38.820] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x24a00d0
[16:38:38.820] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2242360
[16:38:38.820] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fd83dd94010
[16:38:38.820] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fd843fff510
[16:38:38.820] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32768000 fPxarMemory = 0x7fd83dd94010
[16:38:38.821] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 382.7mA
[16:38:38.822] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 462.3mA
[16:38:38.822] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:38:39.320] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x288ade0
[16:38:39.385] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:38:39.385] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:38:39.385] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:38:39.391] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:38:39.391] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:38:39.391] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:38:39.402] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:38:39.402] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:38:39.429] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:38:39.429] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:38:39.444] INFO: PixTestCmd::init()
[16:38:39.452] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:38:39.452] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:38:39.452] INFO: readGainPedestalParameters data/mp243/phCalibrationFitErr35_C0.dat .. data/mp243/phCalibrationFitErr35_C15.dat
[16:38:39.694] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:38:39.694] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:38:39.707] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:38:39.715] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:38:39.715] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:38:39.715] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:38:39.737] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:38:39.737] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:38:39.737] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:38:39.737] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:38:39.737] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:38:39.745] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:38:39.745] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:38:39.766] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:38:39.766] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:38:39.766] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:38:39.767] INFO: readReadbackCal: data/mp243/readbackCal_C0.dat .. data/mp243/readbackCal_C15.dat
[16:38:39.778] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:38:39.790] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:38:39.790] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:38:39.790] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:38:39.791] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:38:39.806] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:38:39.806] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:38:43.453] INFO: ######################################################################
[16:38:43.453] INFO: PixTestAlive::doTest()
[16:38:43.453] INFO: ######################################################################
[16:38:43.457] INFO: ----------------------------------------------------------------------
[16:38:43.457] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:38:43.457] INFO: ----------------------------------------------------------------------
[16:38:43.459] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:38:43.840] INFO: Expecting 41600 events.
[16:38:48.037] INFO: 41600 events read in total (3479ms).
[16:38:48.184] INFO: Test took 4725ms.
[16:38:48.194] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:48.194] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[16:38:48.194] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:38:48.463] INFO: PixTestAlive::aliveTest() done
[16:38:48.463] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:38:48.463] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 40 32 41 83 82 75 80 80 74 69 74 72 76 53 23 30
[16:38:48.487] INFO: ----------------------------------------------------------------------
[16:38:48.487] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:38:48.487] INFO: ----------------------------------------------------------------------
[16:38:48.490] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:38:48.868] INFO: Expecting 41600 events.
[16:38:51.806] INFO: 41600 events read in total (2223ms).
[16:38:51.806] INFO: Test took 3316ms.
[16:38:51.807] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:51.807] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:38:51.807] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:38:51.807] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:38:52.230] INFO: PixTestAlive::maskTest() done
[16:38:52.230] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:38:52.252] INFO: ----------------------------------------------------------------------
[16:38:52.252] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:38:52.252] INFO: ----------------------------------------------------------------------
[16:38:52.255] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:38:52.632] INFO: Expecting 41600 events.
[16:38:56.830] INFO: 41600 events read in total (3483ms).
[16:38:56.832] INFO: Test took 4577ms.
[16:38:56.843] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:56.843] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[16:38:56.843] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:38:57.212] INFO: PixTestAlive::addressDecodingTest() done
[16:38:57.212] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:38:57.213] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[16:39:09.874] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:39:09.874] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:39:09.874] INFO: ----------------------------------------------------------------------
[16:39:09.874] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:39:09.874] INFO: ----------------------------------------------------------------------
[16:39:10.014] INFO: Expecting 768 events.
[16:39:11.142] INFO: 768 events read in total (413ms).
[16:39:11.143] INFO: Test took 1263ms.
[16:39:11.148] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:39:11.946] INFO: Expecting 41600 events.
[16:39:15.665] INFO: 41600 events read in total (3193ms).
[16:39:15.670] INFO: Test took 4522ms.
[16:39:15.825] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:15.825] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 892095
[16:39:15.825] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:39:15.825] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:39:15.889] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:39:16.554] INFO: Expecting 41600 events.
[16:39:20.374] INFO: 41600 events read in total (3293ms).
[16:39:20.380] INFO: Test took 4491ms.
[16:39:20.528] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:20.528] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 898954
[16:39:20.528] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:39:20.528] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:39:20.597] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:39:21.265] INFO: Expecting 41600 events.
[16:39:25.110] INFO: 41600 events read in total (3319ms).
[16:39:25.116] INFO: Test took 4519ms.
[16:39:25.263] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:25.263] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904838
[16:39:25.263] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:39:25.263] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:39:25.334] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:39:25.999] INFO: Expecting 41600 events.
[16:39:29.844] INFO: 41600 events read in total (3319ms).
[16:39:29.849] INFO: Test took 4515ms.
[16:39:29.996] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:29.996] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904085
[16:39:29.996] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:39:29.997] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:39:30.065] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:39:30.729] INFO: Expecting 41600 events.
[16:39:34.574] INFO: 41600 events read in total (3318ms).
[16:39:34.579] INFO: Test took 4514ms.
[16:39:34.727] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:34.727] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 902469
[16:39:34.727] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:39:34.727] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:39:34.796] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:39:35.461] INFO: Expecting 41600 events.
[16:39:39.316] INFO: 41600 events read in total (3327ms).
[16:39:39.321] INFO: Test took 4525ms.
[16:39:39.468] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:39.468] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 902188
[16:39:39.468] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:39:39.468] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:39:39.538] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:39:40.203] INFO: Expecting 41600 events.
[16:39:44.068] INFO: 41600 events read in total (3338ms).
[16:39:44.074] INFO: Test took 4536ms.
[16:39:44.221] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:44.221] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904088
[16:39:44.221] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:39:44.221] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:39:44.289] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:39:44.954] INFO: Expecting 41600 events.
[16:39:48.833] INFO: 41600 events read in total (3352ms).
[16:39:48.838] INFO: Test took 4549ms.
[16:39:48.986] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:48.986] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 903559
[16:39:48.986] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:39:48.987] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:39:49.056] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:39:49.721] INFO: Expecting 41600 events.
[16:39:53.595] INFO: 41600 events read in total (3347ms).
[16:39:53.600] INFO: Test took 4544ms.
[16:39:53.752] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:53.752] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 905042
[16:39:53.752] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:39:53.753] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:39:53.819] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:39:54.484] INFO: Expecting 41600 events.
[16:39:58.358] INFO: 41600 events read in total (3348ms).
[16:39:58.363] INFO: Test took 4544ms.
[16:39:58.514] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:58.514] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 903218
[16:39:58.514] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:39:58.514] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:39:58.580] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:39:59.245] INFO: Expecting 41600 events.
[16:40:03.119] INFO: 41600 events read in total (3347ms).
[16:40:03.125] INFO: Test took 4545ms.
[16:40:03.272] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:03.272] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904559
[16:40:03.272] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:40:03.272] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:03.341] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:04.006] INFO: Expecting 41600 events.
[16:40:07.879] INFO: 41600 events read in total (3347ms).
[16:40:07.885] INFO: Test took 4544ms.
[16:40:08.031] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:08.031] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 905029
[16:40:08.031] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:40:08.032] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:08.101] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:08.766] INFO: Expecting 41600 events.
[16:40:12.646] INFO: 41600 events read in total (3353ms).
[16:40:12.651] INFO: Test took 4550ms.
[16:40:12.797] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:12.797] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904877
[16:40:12.797] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:40:12.797] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:12.866] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:13.531] INFO: Expecting 41600 events.
[16:40:17.405] INFO: 41600 events read in total (3347ms).
[16:40:17.411] INFO: Test took 4545ms.
[16:40:17.558] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:17.558] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904033
[16:40:17.558] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:40:17.558] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:17.627] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:18.292] INFO: Expecting 41600 events.
[16:40:22.163] INFO: 41600 events read in total (3344ms).
[16:40:22.169] INFO: Test took 4542ms.
[16:40:22.316] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:22.316] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904839
[16:40:22.316] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:40:22.316] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:22.385] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:23.050] INFO: Expecting 41600 events.
[16:40:26.915] INFO: 41600 events read in total (3338ms).
[16:40:26.920] INFO: Test took 4535ms.
[16:40:27.066] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:27.067] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 903343
[16:40:27.067] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:40:27.067] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:27.136] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:27.801] INFO: Expecting 41600 events.
[16:40:31.679] INFO: 41600 events read in total (3351ms).
[16:40:31.684] INFO: Test took 4548ms.
[16:40:31.834] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:31.834] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904424
[16:40:31.834] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:40:31.835] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:31.901] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:32.566] INFO: Expecting 41600 events.
[16:40:36.443] INFO: 41600 events read in total (3351ms).
[16:40:36.449] INFO: Test took 4548ms.
[16:40:36.604] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:36.605] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904493
[16:40:36.605] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:40:36.605] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:36.672] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:37.338] INFO: Expecting 41600 events.
[16:40:41.160] INFO: 41600 events read in total (3295ms).
[16:40:41.166] INFO: Test took 4494ms.
[16:40:41.312] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:41.312] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 901878
[16:40:41.312] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:40:41.312] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:41.382] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:42.046] INFO: Expecting 41600 events.
[16:40:45.701] INFO: 41600 events read in total (3128ms).
[16:40:45.706] INFO: Test took 4324ms.
[16:40:45.852] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:45.853] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 895025
[16:40:45.853] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:40:45.853] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:46.143] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 169 eff = 0.998606
[16:40:46.143] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 156 eff = 0.999375
[16:40:46.143] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 181 eff = 0.998197
[16:40:46.144] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 165 eff = 0.996298
[16:40:46.144] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 157 eff = 0.995625
[16:40:46.144] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 162 eff = 0.995529
[16:40:46.144] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 174 eff = 0.995697
[16:40:46.144] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 164 eff = 0.99637
[16:40:46.144] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 176 eff = 0.995962
[16:40:46.144] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 182 eff = 0.995625
[16:40:46.145] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 177 eff = 0.99524
[16:40:46.145] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 182 eff = 0.995673
[16:40:46.145] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 152 eff = 0.995889
[16:40:46.145] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 168 eff = 0.997572
[16:40:46.145] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 155 eff = 0.999351
[16:40:46.145] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 173 eff = 0.99887
[16:40:50.242] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:40:50.242] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:40:50.242] INFO: ----------------------------------------------------------------------
[16:40:50.242] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:40:50.242] INFO: ----------------------------------------------------------------------
[16:40:50.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:40:50.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:40:50.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:40:50.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:40:50.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:40:50.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:40:50.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:40:50.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:40:50.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:40:50.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:40:50.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:40:50.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:40:50.261] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:50.930] INFO: Expecting 208000 events.
[16:41:07.942] INFO: 208000 events read in total (16485ms).
[16:41:07.963] INFO: Test took 17702ms.
[16:41:08.782] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:08.782] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4204246
[16:41:08.782] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:41:08.783] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:41:09.294] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:41:09.294] INFO: number of red-efficiency pixels: 261 159 398 697 752 814 741 731 765 864 888 796 783 476 183 239
[16:41:09.294] INFO: number of X-ray hits detected: 162905 113751 199030 308013 318932 324125 313504 296055 319182 329164 332923 316912 315567 206839 123163 157622
[16:41:09.294] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:41:09.294] INFO: number of Vcal hits detected: 207677 207834 207537 207189 207056 207057 207145 207174 207145 207008 206954 207064 207100 207459 207813 207750
[16:41:09.294] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.6 99.6 99.6 99.6 99.5 99.5 99.6 99.6 99.8 99.9 99.9
[16:41:09.294] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.6 99.5 99.5 99.6 99.6 99.6 99.5 99.5 99.6 99.6 99.7 99.9 99.9
[16:41:09.294] INFO: X-ray hit rate [MHz/cm2]: 47.7 33.3 58.3 90.3 93.5 95.0 91.9 86.8 93.6 96.5 97.6 92.9 92.5 60.6 36.1 46.2
[16:41:09.294] INFO: PixTestHighRate::doXPixelAlive() done
[16:41:09.339] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:41:09.340] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:41:09.340] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:41:09.340] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:41:09.340] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:41:09.340] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:41:09.340] INFO: PixTest:: pg_setup set to default.
[16:41:14.202] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:41:14.202] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[16:41:15.626] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:41:15.627] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.1mA
[16:41:15.628] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 463.1mA
[16:41:15.628] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.5 C
[16:41:15.628] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:41:15.628] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:41:15.628] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:41:15.628] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:41:15.628] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:41:15.628] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:41:15.629] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:41:15.629] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:41:15.629] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:41:15.629] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:41:15.723] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:41:15.723] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:41:15.732] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:41:15.732] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:41:15.732] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:41:15.732] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:41:15.732] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:41:15.732] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:41:15.735] QUIET: Connection to board 43 closed.
[16:41:16.937] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master