Test Date: 1973-11-29 16:33
Analysis date: 2016-06-17 13:05
Logfile
hrData_40.log
[16:48:22.268] INFO: *** Welcome to pxar ***
[16:48:22.268] INFO: *** Today: 2016/06/16
[16:48:22.294] INFO: *** Version: v1.9.0-814-g7497
[16:48:22.294] INFO: readRocDacs: data/mp237/dacParameters35_C0.dat .. data/mp237/dacParameters35_C15.dat
[16:48:22.295] INFO: readTbmDacs: data/mp237/tbmParameters_C0a.dat .. data/mp237/tbmParameters_C0b.dat
[16:48:22.295] INFO: readMaskFile: data/mp237/defaultMaskFile.dat
[16:48:22.295] INFO: readTrimFile: data/mp237/trimParameters35_C0.dat .. data/mp237/trimParameters35_C15.dat
[16:48:22.447] INFO: clk: 4
[16:48:22.447] INFO: ctr: 4
[16:48:22.447] INFO: sda: 19
[16:48:22.447] INFO: tin: 9
[16:48:22.447] INFO: level: 15
[16:48:22.447] INFO: triggerdelay: 0
[16:48:22.448] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:48:22.448] INFO: Log level: DEBUG
[16:48:22.458] QUIET: Connection to board DTB_WRMNMR opened.
[16:48:22.462] 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:22.464] INFO: RPC call hashes of host and DTB match: 398089610
[16:48:24.066] INFO: DUT info:
[16:48:24.067] INFO: The DUT currently contains the following objects:
[16:48:24.067] INFO: 2 TBM Cores tbm08c (2 ON)
[16:48:24.067] INFO: TBM Core alpha (0): 7 registers set
[16:48:24.067] INFO: TBM Core beta (1): 7 registers set
[16:48:24.067] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:48:24.067] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:24.067] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:48:24.068] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:48:24.069] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:48:24.070] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:48:24.071] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:48:24.071] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:48:24.073] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32817152
[16:48:24.073] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2601bd0
[16:48:24.073] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x23a3e60
[16:48:24.073] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f5659d94010
[16:48:24.073] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f565ffff510
[16:48:24.073] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32825344 fPxarMemory = 0x7f5659d94010
[16:48:24.074] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 384.3mA
[16:48:24.075] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 460.6mA
[16:48:24.075] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[16:48:24.569] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x29ec850
[16:48:24.640] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:48:24.640] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:48:24.640] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:48:24.646] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:48:24.646] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:48:24.646] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:48:24.668] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:48:24.668] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:48:24.678] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:48:24.678] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:48:24.693] INFO: PixTestCmd::init()
[16:48:24.705] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:48:24.705] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:48:24.705] INFO: readGainPedestalParameters data/mp237/phCalibrationFitErr35_C0.dat .. data/mp237/phCalibrationFitErr35_C15.dat
[16:48:24.949] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:48:24.949] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:48:24.962] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:48:24.968] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:48:24.968] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:48:24.968] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:48:24.992] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:48:24.993] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:48:24.993] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:48:24.993] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:48:24.993] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:48:24.999] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:48:24.999] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:48:25.017] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:48:25.017] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:48:25.017] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:48:25.017] INFO: readReadbackCal: data/mp237/readbackCal_C0.dat .. data/mp237/readbackCal_C15.dat
[16:48:25.027] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:48:25.035] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:48:25.035] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:48:25.035] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:48:25.035] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:48:25.054] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:48:25.054] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:48:35.977] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[16:48:38.169] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[16:48:39.646] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[16:48:39.646] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[16:48:39.646] INFO: ----------------------------------------------------------------------
[16:48:39.646] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[16:48:39.646] INFO: ----------------------------------------------------------------------
[16:48:40.620] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[16:48:52.564] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[16:48:52.568] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:49:19.686] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1176852 events.
[16:49:26.440] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1176852, pixels seen in all events: 6129848
[16:49:26.542] INFO: Resuming triggers.
[16:49:38.485] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[16:49:38.527] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:50:05.574] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1176554 events.
[16:50:12.334] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1176554, pixels seen in all events: 6132942
[16:50:12.517] INFO: Resuming triggers.
[16:50:24.467] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[16:50:24.508] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:50:51.560] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1177255 events.
[16:50:58.305] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1177255, pixels seen in all events: 6130106
[16:50:58.484] INFO: Resuming triggers.
[16:51:10.428] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[16:51:10.470] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:51:37.603] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1176654 events.
[16:51:44.380] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1176654, pixels seen in all events: 6131421
[16:51:44.561] INFO: Resuming triggers.
[16:51:56.504] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[16:51:56.541] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:52:23.639] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1176505 events.
[16:52:30.400] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1176505, pixels seen in all events: 6133549
[16:52:30.586] INFO: Resuming triggers.
[16:52:30.937] INFO: data taking finished, elapsed time: 60 seconds.
[16:52:31.133] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:52:32.112] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 34453 events.
[16:52:32.309] DEBUG: <PixTestXray.cc/processData:L823> # events read: 34453, pixels seen in all events: 179746
[16:52:32.356] INFO: PixTest:: pg_setup set to default.
[16:52:32.405] INFO: PixTestXray::doPhRun() done
[16:53:08.174] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:53:08.175] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 388.3mA
[16:53:08.176] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 461.4mA
[16:53:08.176] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[16:53:08.176] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:53:08.176] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:53:08.176] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:53:08.176] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:53:08.176] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:53:08.176] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:53:08.176] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:53:08.176] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:53:08.176] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:53:08.177] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:53:08.177] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:53:08.177] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:53:08.177] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:53:08.177] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:53:08.177] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:53:08.177] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:53:08.177] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:53:08.177] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:53:08.455] QUIET: Connection to board 43 closed.
[16:53:08.534] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[16:53:14.090] INFO: *** Welcome to pxar ***
[16:53:14.090] INFO: *** Today: 2016/06/16
[16:53:14.115] INFO: *** Version: v1.9.0-814-g7497
[16:53:14.115] INFO: readRocDacs: data/mp237/dacParameters35_C0.dat .. data/mp237/dacParameters35_C15.dat
[16:53:14.116] INFO: readTbmDacs: data/mp237/tbmParameters_C0a.dat .. data/mp237/tbmParameters_C0b.dat
[16:53:14.116] INFO: readMaskFile: data/mp237/defaultMaskFile.dat
[16:53:14.116] INFO: readTrimFile: data/mp237/trimParameters35_C0.dat .. data/mp237/trimParameters35_C15.dat
[16:53:14.270] INFO: clk: 4
[16:53:14.270] INFO: ctr: 4
[16:53:14.270] INFO: sda: 19
[16:53:14.270] INFO: tin: 9
[16:53:14.270] INFO: level: 15
[16:53:14.270] INFO: triggerdelay: 0
[16:53:14.270] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:53:14.270] INFO: Log level: DEBUG
[16:53:14.281] QUIET: Connection to board DTB_WRMNMR opened.
[16:53:14.284] 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:53:14.287] INFO: RPC call hashes of host and DTB match: 398089610
[16:53:15.891] INFO: DUT info:
[16:53:15.891] INFO: The DUT currently contains the following objects:
[16:53:15.891] INFO: 2 TBM Cores tbm08c (2 ON)
[16:53:15.892] INFO: TBM Core alpha (0): 7 registers set
[16:53:15.892] INFO: TBM Core beta (1): 7 registers set
[16:53:15.892] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:53:15.892] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:53:15.892] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:53:15.892] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:53:15.892] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:53:15.893] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:53:15.894] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:53:15.895] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:53:15.896] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:53:15.896] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:53:15.898] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31395840
[16:53:15.898] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x16cebd0
[16:53:15.898] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1470e60
[16:53:15.898] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f66edd94010
[16:53:15.898] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f66f3fff510
[16:53:15.898] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31404032 fPxarMemory = 0x7f66edd94010
[16:53:15.899] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[16:53:15.900] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 460.6mA
[16:53:15.900] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[16:53:16.395] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1ab9850
[16:53:16.455] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:53:16.456] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:53:16.456] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:53:16.461] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:53:16.461] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:53:16.461] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:53:16.476] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:53:16.476] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:53:16.493] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:53:16.493] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:53:16.516] INFO: PixTestCmd::init()
[16:53:16.527] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:53:16.527] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:53:16.527] INFO: readGainPedestalParameters data/mp237/phCalibrationFitErr35_C0.dat .. data/mp237/phCalibrationFitErr35_C15.dat
[16:53:16.769] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:53:16.770] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:53:16.785] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:53:16.790] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:53:16.790] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:53:16.791] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:53:16.814] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:53:16.814] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:53:16.814] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:53:16.814] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:53:16.814] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:53:16.820] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:53:16.820] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:53:16.844] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:53:16.844] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:53:16.844] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:53:16.844] INFO: readReadbackCal: data/mp237/readbackCal_C0.dat .. data/mp237/readbackCal_C15.dat
[16:53:16.853] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:53:16.862] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:53:16.862] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:53:16.862] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:53:16.862] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:53:16.882] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:53:16.882] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:53:23.705] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[16:53:26.633] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[16:53:28.366] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[16:53:28.366] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[16:53:28.366] INFO: ----------------------------------------------------------------------
[16:53:28.366] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[16:53:28.366] INFO: ----------------------------------------------------------------------
[16:53:29.340] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[16:53:36.950] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[16:53:36.954] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:54:02.022] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 749864 events.
[16:54:14.407] DEBUG: <PixTestXray.cc/processData:L823> # events read: 749864, pixels seen in all events: 11255313
[16:54:14.712] INFO: Resuming triggers.
[16:54:22.331] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[16:54:22.372] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:54:47.255] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 750502 events.
[16:54:59.353] DEBUG: <PixTestXray.cc/processData:L823> # events read: 750502, pixels seen in all events: 11245726
[16:54:59.575] INFO: Resuming triggers.
[16:55:07.192] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[16:55:07.234] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:55:32.173] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 750403 events.
[16:55:44.301] DEBUG: <PixTestXray.cc/processData:L823> # events read: 750403, pixels seen in all events: 11246819
[16:55:44.528] INFO: Resuming triggers.
[16:55:52.145] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[16:55:52.185] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:56:17.043] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 750317 events.
[16:56:29.164] DEBUG: <PixTestXray.cc/processData:L823> # events read: 750317, pixels seen in all events: 11247506
[16:56:29.384] INFO: Resuming triggers.
[16:56:37.003] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[16:56:37.043] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:57:01.905] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 750520 events.
[16:57:14.007] DEBUG: <PixTestXray.cc/processData:L823> # events read: 750520, pixels seen in all events: 11245733
[16:57:14.230] INFO: Resuming triggers.
[16:57:21.850] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[16:57:21.890] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:57:46.829] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 750582 events.
[16:57:58.988] DEBUG: <PixTestXray.cc/processData:L823> # events read: 750582, pixels seen in all events: 11244980
[16:57:59.212] INFO: Resuming triggers.
[16:58:06.831] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[16:58:06.874] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:58:31.741] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 750504 events.
[16:58:43.896] DEBUG: <PixTestXray.cc/processData:L823> # events read: 750504, pixels seen in all events: 11246358
[16:58:44.124] INFO: Resuming triggers.
[16:58:50.884] INFO: data taking finished, elapsed time: 60 seconds.
[16:58:51.080] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:59:13.176] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 665836 events.
[16:59:23.920] DEBUG: <PixTestXray.cc/processData:L823> # events read: 665836, pixels seen in all events: 9974659
[16:59:24.120] INFO: PixTest:: pg_setup set to default.
[16:59:24.165] INFO: PixTestXray::doPhRun() done
[17:01:08.886] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:01:08.887] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 388.3mA
[17:01:08.888] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 461.4mA
[17:01:08.888] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[17:01:08.888] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:01:08.888] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:01:08.888] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:01:08.888] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:01:08.888] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:01:08.888] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:01:08.889] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:01:08.889] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:01:08.889] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:01:08.889] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[17:01:08.889] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:01:08.889] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:01:08.889] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:01:08.889] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:01:08.889] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:01:08.889] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:01:08.889] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:01:08.889] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:01:09.200] QUIET: Connection to board 43 closed.
[17:01:09.279] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[16:42:39.073] INFO: *** Welcome to pxar ***
[16:42:39.073] INFO: *** Today: 2016/06/16
[16:42:39.100] INFO: *** Version: v1.9.0-814-g7497
[16:42:39.101] INFO: readRocDacs: data/mp237/dacParameters35_C0.dat .. data/mp237/dacParameters35_C15.dat
[16:42:39.102] INFO: readTbmDacs: data/mp237/tbmParameters_C0a.dat .. data/mp237/tbmParameters_C0b.dat
[16:42:39.102] INFO: readMaskFile: data/mp237/defaultMaskFile.dat
[16:42:39.102] INFO: readTrimFile: data/mp237/trimParameters35_C0.dat .. data/mp237/trimParameters35_C15.dat
[16:42:39.254] INFO: clk: 4
[16:42:39.254] INFO: ctr: 4
[16:42:39.254] INFO: sda: 19
[16:42:39.254] INFO: tin: 9
[16:42:39.254] INFO: level: 15
[16:42:39.254] INFO: triggerdelay: 0
[16:42:39.254] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:42:39.254] INFO: Log level: DEBUG
[16:42:39.266] QUIET: Connection to board DTB_WRMNMR opened.
[16:42:39.270] 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:42:39.272] INFO: RPC call hashes of host and DTB match: 398089610
[16:42:40.875] INFO: DUT info:
[16:42:40.875] INFO: The DUT currently contains the following objects:
[16:42:40.875] INFO: 2 TBM Cores tbm08c (2 ON)
[16:42:40.875] INFO: TBM Core alpha (0): 7 registers set
[16:42:40.875] INFO: TBM Core beta (1): 7 registers set
[16:42:40.875] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:42:40.875] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.875] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.875] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.875] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.875] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.875] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.875] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.875] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.875] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.875] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.875] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.875] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.875] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.875] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.875] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.875] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:42:40.876] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:42:40.877] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:42:40.878] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:42:40.879] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:42:40.879] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:42:40.879] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:42:40.879] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:42:40.879] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:42:40.879] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:42:40.879] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:42:40.879] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:42:40.879] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:42:40.879] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:42:40.879] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:42:40.879] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:42:40.879] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:42:40.881] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34840576
[16:42:40.881] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2700bd0
[16:42:40.881] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x24a2e60
[16:42:40.881] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fd721d94010
[16:42:40.881] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fd727fff510
[16:42:40.881] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34848768 fPxarMemory = 0x7fd721d94010
[16:42:40.882] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[16:42:40.883] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 461.4mA
[16:42:40.883] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[16:42:41.378] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2aeb7f0
[16:42:41.438] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:42:41.438] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:42:41.439] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:42:41.444] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:42:41.444] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:42:41.444] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:42:41.454] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:42:41.454] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:42:41.472] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:42:41.472] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:42:41.493] INFO: PixTestCmd::init()
[16:42:41.507] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:42:41.507] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:42:41.507] INFO: readGainPedestalParameters data/mp237/phCalibrationFitErr35_C0.dat .. data/mp237/phCalibrationFitErr35_C15.dat
[16:42:41.751] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:42:41.752] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:42:41.766] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:42:41.773] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:42:41.773] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:42:41.773] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:42:41.797] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:42:41.797] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:42:41.797] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:42:41.797] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:42:41.797] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:42:41.803] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:42:41.804] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:42:41.825] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:42:41.825] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:42:41.825] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:42:41.825] INFO: readReadbackCal: data/mp237/readbackCal_C0.dat .. data/mp237/readbackCal_C15.dat
[16:42:41.834] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:42:41.843] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:42:41.843] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:42:41.843] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:42:41.843] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:42:41.861] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:42:41.861] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:42:44.785] INFO: ######################################################################
[16:42:44.785] INFO: PixTestAlive::doTest()
[16:42:44.785] INFO: ######################################################################
[16:42:44.789] INFO: ----------------------------------------------------------------------
[16:42:44.789] 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:42:44.789] INFO: ----------------------------------------------------------------------
[16:42:44.791] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:45.172] INFO: Expecting 41600 events.
[16:42:49.381] INFO: 41600 events read in total (3491ms).
[16:42:49.528] INFO: Test took 4737ms.
[16:42:49.538] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:49.538] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:42:49.538] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:42:49.814] INFO: PixTestAlive::aliveTest() done
[16:42:49.814] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:42:49.814] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 11 15 20 43 50 41 37 33 45 47 50 35 36 24 14 20
[16:42:49.842] INFO: ----------------------------------------------------------------------
[16:42:49.842] 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:42:49.842] INFO: ----------------------------------------------------------------------
[16:42:49.847] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:50.226] INFO: Expecting 41600 events.
[16:42:53.150] INFO: 41600 events read in total (2208ms).
[16:42:53.150] INFO: Test took 3303ms.
[16:42:53.150] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:53.150] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:42:53.150] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:42:53.151] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:42:53.573] INFO: PixTestAlive::maskTest() done
[16:42:53.573] 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:42:53.595] INFO: ----------------------------------------------------------------------
[16:42:53.595] 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:42:53.595] INFO: ----------------------------------------------------------------------
[16:42:53.597] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:42:53.974] INFO: Expecting 41600 events.
[16:42:58.177] INFO: 41600 events read in total (3487ms).
[16:42:58.179] INFO: Test took 4582ms.
[16:42:58.190] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:58.190] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:42:58.190] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:42:58.560] INFO: PixTestAlive::addressDecodingTest() done
[16:42:58.560] 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:42:58.561] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[16:43:27.582] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:43:27.582] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:43:27.582] INFO: ----------------------------------------------------------------------
[16:43:27.582] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:43:27.582] INFO: ----------------------------------------------------------------------
[16:43:27.721] INFO: Expecting 768 events.
[16:43:28.850] INFO: 768 events read in total (413ms).
[16:43:28.850] INFO: Test took 1262ms.
[16:43:28.856] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:43:29.653] INFO: Expecting 41600 events.
[16:43:32.822] INFO: 41600 events read in total (2642ms).
[16:43:32.826] INFO: Test took 3970ms.
[16:43:32.909] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:32.909] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 473384
[16:43:32.909] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:43:32.910] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:43:32.948] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:43:33.613] INFO: Expecting 41600 events.
[16:43:36.891] INFO: 41600 events read in total (2752ms).
[16:43:36.895] INFO: Test took 3947ms.
[16:43:36.973] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:36.973] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 480450
[16:43:36.973] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:43:36.974] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:43:37.013] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:43:37.678] INFO: Expecting 41600 events.
[16:43:41.006] INFO: 41600 events read in total (2801ms).
[16:43:41.010] INFO: Test took 3997ms.
[16:43:41.090] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:41.091] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483429
[16:43:41.091] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:43:41.091] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:43:41.131] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:43:41.796] INFO: Expecting 41600 events.
[16:43:45.140] INFO: 41600 events read in total (2819ms).
[16:43:45.144] INFO: Test took 4013ms.
[16:43:45.223] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:45.223] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 484394
[16:43:45.223] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:43:45.224] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:43:45.264] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:43:45.929] INFO: Expecting 41600 events.
[16:43:49.275] INFO: 41600 events read in total (2820ms).
[16:43:49.279] INFO: Test took 4015ms.
[16:43:49.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:49.360] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 484464
[16:43:49.360] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:43:49.360] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:43:49.400] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:43:50.065] INFO: Expecting 41600 events.
[16:43:53.417] INFO: 41600 events read in total (2825ms).
[16:43:53.420] INFO: Test took 4020ms.
[16:43:53.503] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:53.503] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 485438
[16:43:53.503] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:43:53.504] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:43:53.546] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:43:54.212] INFO: Expecting 41600 events.
[16:43:57.564] INFO: 41600 events read in total (2825ms).
[16:43:57.568] INFO: Test took 4022ms.
[16:43:57.649] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:57.649] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 485116
[16:43:57.649] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:43:57.650] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:43:57.691] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:43:58.358] INFO: Expecting 41600 events.
[16:44:01.711] INFO: 41600 events read in total (2827ms).
[16:44:01.714] INFO: Test took 4023ms.
[16:44:01.796] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:01.796] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 484199
[16:44:01.796] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:44:01.796] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:01.838] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:02.505] INFO: Expecting 41600 events.
[16:44:05.859] INFO: 41600 events read in total (2828ms).
[16:44:05.862] INFO: Test took 4024ms.
[16:44:05.941] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:05.941] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 484306
[16:44:05.941] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:44:05.942] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:05.982] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:06.647] INFO: Expecting 41600 events.
[16:44:09.998] INFO: 41600 events read in total (2825ms).
[16:44:10.001] INFO: Test took 4019ms.
[16:44:10.081] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:10.081] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483796
[16:44:10.081] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:44:10.081] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:10.121] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:10.786] INFO: Expecting 41600 events.
[16:44:14.140] INFO: 41600 events read in total (2827ms).
[16:44:14.143] INFO: Test took 4022ms.
[16:44:14.223] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:14.223] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483503
[16:44:14.223] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:44:14.224] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:14.264] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:14.929] INFO: Expecting 41600 events.
[16:44:18.277] INFO: 41600 events read in total (2821ms).
[16:44:18.281] INFO: Test took 4017ms.
[16:44:18.362] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:18.362] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483916
[16:44:18.362] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:44:18.362] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:18.405] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:19.071] INFO: Expecting 41600 events.
[16:44:22.422] INFO: 41600 events read in total (2824ms).
[16:44:22.426] INFO: Test took 4021ms.
[16:44:22.505] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:22.505] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483703
[16:44:22.505] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:44:22.505] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:22.546] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:23.211] INFO: Expecting 41600 events.
[16:44:26.512] INFO: 41600 events read in total (2775ms).
[16:44:26.516] INFO: Test took 3970ms.
[16:44:26.599] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:26.599] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483948
[16:44:26.599] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:44:26.599] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:26.641] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:27.310] INFO: Expecting 41600 events.
[16:44:30.650] INFO: 41600 events read in total (2813ms).
[16:44:30.654] INFO: Test took 4013ms.
[16:44:30.735] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:30.735] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483634
[16:44:30.735] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:44:30.735] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:30.777] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:31.444] INFO: Expecting 41600 events.
[16:44:34.795] INFO: 41600 events read in total (2824ms).
[16:44:34.799] INFO: Test took 4022ms.
[16:44:34.878] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:34.878] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483046
[16:44:34.878] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:44:34.878] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:34.918] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:35.583] INFO: Expecting 41600 events.
[16:44:38.935] INFO: 41600 events read in total (2825ms).
[16:44:38.939] INFO: Test took 4021ms.
[16:44:39.019] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:39.019] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483131
[16:44:39.019] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:44:39.020] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:39.061] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:39.728] INFO: Expecting 41600 events.
[16:44:43.072] INFO: 41600 events read in total (2817ms).
[16:44:43.076] INFO: Test took 4015ms.
[16:44:43.156] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:43.156] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483354
[16:44:43.156] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:44:43.157] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:43.200] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:43.867] INFO: Expecting 41600 events.
[16:44:47.204] INFO: 41600 events read in total (2810ms).
[16:44:47.208] INFO: Test took 4008ms.
[16:44:47.288] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:47.288] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 482685
[16:44:47.288] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:44:47.289] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:47.329] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:44:47.993] INFO: Expecting 41600 events.
[16:44:51.199] INFO: 41600 events read in total (2679ms).
[16:44:51.203] INFO: Test took 3874ms.
[16:44:51.281] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:51.281] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476391
[16:44:51.281] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:44:51.282] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:44:51.542] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 162 eff = 0.999639
[16:44:51.543] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 137 eff = 0.999784
[16:44:51.543] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 145 eff = 0.999495
[16:44:51.543] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 145 eff = 0.999159
[16:44:51.544] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 161 eff = 0.999087
[16:44:51.544] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 162 eff = 0.998942
[16:44:51.544] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 147 eff = 0.998918
[16:44:51.544] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 155 eff = 0.999087
[16:44:51.545] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 151 eff = 0.999135
[16:44:51.545] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 149 eff = 0.998774
[16:44:51.545] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 143 eff = 0.998726
[16:44:51.545] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 158 eff = 0.99899
[16:44:51.545] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 151 eff = 0.999038
[16:44:51.546] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 141 eff = 0.999447
[16:44:51.546] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 140 eff = 0.99976
[16:44:51.546] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 172 eff = 0.999663
[16:45:11.958] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:45:11.958] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:45:11.958] INFO: ----------------------------------------------------------------------
[16:45:11.958] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:45:11.958] INFO: ----------------------------------------------------------------------
[16:45:11.959] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:45:11.959] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:45:11.959] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:45:11.959] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:45:11.959] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:45:11.959] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:45:11.959] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:45:11.959] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:45:11.959] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:45:11.959] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:45:11.959] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:45:11.959] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:45:11.976] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:45:12.645] INFO: Expecting 208000 events.
[16:45:25.938] INFO: 208000 events read in total (12766ms).
[16:45:25.951] INFO: Test took 13975ms.
[16:45:26.334] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:26.334] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2141016
[16:45:26.334] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:45:26.334] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:45:26.710] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:45:26.710] INFO: number of red-efficiency pixels: 108 74 158 259 279 262 205 224 186 318 351 224 255 161 80 104
[16:45:26.710] INFO: number of X-ray hits detected: 89121 59617 101512 155267 163518 161628 160693 142615 147942 168370 167696 156689 157714 102466 61241 78367
[16:45:26.710] 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:45:26.710] INFO: number of Vcal hits detected: 207890 207925 207836 207735 207708 207731 207785 207770 207806 207660 207629 207760 207735 207832 207918 207893
[16:45:26.710] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 99.9
[16:45:26.710] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 99.9
[16:45:26.710] INFO: X-ray hit rate [MHz/cm2]: 26.1 17.5 29.8 45.5 47.9 47.4 47.1 41.8 43.4 49.4 49.2 45.9 46.2 30.0 18.0 23.0
[16:45:26.710] INFO: PixTestHighRate::doXPixelAlive() done
[16:45:26.755] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:45:26.755] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:45:26.755] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:45:26.755] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:45:26.755] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:45:26.755] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:45:26.755] INFO: PixTest:: pg_setup set to default.
[16:45:30.430] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:45:30.430] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[16:45:32.182] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:45:32.183] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 388.3mA
[16:45:32.184] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 461.4mA
[16:45:32.184] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[16:45:32.184] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:45:32.184] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:45:32.184] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:45:32.184] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:45:32.184] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:45:32.184] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:45:32.184] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:45:32.184] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:45:32.184] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:45:32.184] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:45:32.279] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:45:32.279] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:45:32.288] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:45:32.288] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:45:32.288] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:45:32.288] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:45:32.288] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:45:32.288] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:45:32.290] QUIET: Connection to board 43 closed.
[16:45:32.370] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[16:39:38.932] INFO: *** Welcome to pxar ***
[16:39:38.932] INFO: *** Today: 2016/06/16
[16:39:38.955] INFO: *** Version: v1.9.0-814-g7497
[16:39:38.955] INFO: readRocDacs: data/mp237/dacParameters35_C0.dat .. data/mp237/dacParameters35_C15.dat
[16:39:38.956] INFO: readTbmDacs: data/mp237/tbmParameters_C0a.dat .. data/mp237/tbmParameters_C0b.dat
[16:39:38.956] INFO: readMaskFile: data/mp237/defaultMaskFile.dat
[16:39:38.957] INFO: readTrimFile: data/mp237/trimParameters35_C0.dat .. data/mp237/trimParameters35_C15.dat
[16:39:39.107] INFO: clk: 4
[16:39:39.107] INFO: ctr: 4
[16:39:39.107] INFO: sda: 19
[16:39:39.107] INFO: tin: 9
[16:39:39.107] INFO: level: 15
[16:39:39.107] INFO: triggerdelay: 0
[16:39:39.107] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:39:39.107] INFO: Log level: DEBUG
[16:39:39.119] QUIET: Connection to board DTB_WRMNMR opened.
[16:39:39.122] 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:39:39.125] INFO: RPC call hashes of host and DTB match: 398089610
[16:39:40.727] INFO: DUT info:
[16:39:40.727] INFO: The DUT currently contains the following objects:
[16:39:40.727] INFO: 2 TBM Cores tbm08c (2 ON)
[16:39:40.727] INFO: TBM Core alpha (0): 7 registers set
[16:39:40.727] INFO: TBM Core beta (1): 7 registers set
[16:39:40.727] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:39:40.727] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.727] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.727] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.727] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.727] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.727] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.727] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.727] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.727] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.727] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.727] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.727] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.728] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.728] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.728] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.728] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:39:40.728] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:39:40.729] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:39:40.730] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:39:40.731] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:39:40.733] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35581952
[16:39:40.733] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x27bdbd0
[16:39:40.733] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x255fe60
[16:39:40.733] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f07c5393010
[16:39:40.733] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f07cb5fe510
[16:39:40.733] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35590144 fPxarMemory = 0x7f07c5393010
[16:39:40.734] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[16:39:40.736] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 461.4mA
[16:39:40.736] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:39:41.232] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2ba87e0
[16:39:41.297] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:39:41.297] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:39:41.298] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:39:41.303] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:39:41.303] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:39:41.303] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:39:41.313] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:39:41.313] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:39:41.333] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:39:41.334] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:39:41.349] INFO: PixTestCmd::init()
[16:39:41.359] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:39:41.359] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:39:41.359] INFO: readGainPedestalParameters data/mp237/phCalibrationFitErr35_C0.dat .. data/mp237/phCalibrationFitErr35_C15.dat
[16:39:41.602] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:39:41.603] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:39:41.617] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:39:41.622] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:39:41.622] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:39:41.622] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:39:41.647] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:39:41.647] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:39:41.647] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:39:41.647] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:39:41.647] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:39:41.653] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:39:41.653] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:39:41.677] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:39:41.677] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:39:41.677] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:39:41.677] INFO: readReadbackCal: data/mp237/readbackCal_C0.dat .. data/mp237/readbackCal_C15.dat
[16:39:41.686] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:39:41.694] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:39:41.694] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:39:41.694] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:39:41.694] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:39:41.712] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:39:41.712] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:39:45.425] INFO: ######################################################################
[16:39:45.425] INFO: PixTestAlive::doTest()
[16:39:45.425] INFO: ######################################################################
[16:39:45.429] INFO: ----------------------------------------------------------------------
[16:39:45.429] 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:39:45.429] INFO: ----------------------------------------------------------------------
[16:39:45.431] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:39:45.810] INFO: Expecting 41600 events.
[16:39:49.985] INFO: 41600 events read in total (3457ms).
[16:39:50.133] INFO: Test took 4702ms.
[16:39:50.147] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:50.147] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:39:50.147] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:39:50.431] INFO: PixTestAlive::aliveTest() done
[16:39:50.432] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:39:50.432] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 40 24 38 56 68 73 73 64 48 54 69 70 53 42 22 27
[16:39:50.457] INFO: ----------------------------------------------------------------------
[16:39:50.457] 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:39:50.457] INFO: ----------------------------------------------------------------------
[16:39:50.459] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:39:50.836] INFO: Expecting 41600 events.
[16:39:53.768] INFO: 41600 events read in total (2217ms).
[16:39:53.769] INFO: Test took 3310ms.
[16:39:53.769] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:53.769] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:39:53.769] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:39:53.769] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:39:54.191] INFO: PixTestAlive::maskTest() done
[16:39:54.191] 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:39:54.214] INFO: ----------------------------------------------------------------------
[16:39:54.214] 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:39:54.214] INFO: ----------------------------------------------------------------------
[16:39:54.216] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:39:54.594] INFO: Expecting 41600 events.
[16:39:58.781] INFO: 41600 events read in total (3472ms).
[16:39:58.782] INFO: Test took 4566ms.
[16:39:58.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:58.794] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:39:58.794] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:39:59.165] INFO: PixTestAlive::addressDecodingTest() done
[16:39:59.165] 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:39:59.165] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[16:40:04.846] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:40:04.846] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:40:04.846] INFO: ----------------------------------------------------------------------
[16:40:04.846] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:40:04.846] INFO: ----------------------------------------------------------------------
[16:40:04.985] INFO: Expecting 768 events.
[16:40:06.114] INFO: 768 events read in total (414ms).
[16:40:06.114] INFO: Test took 1262ms.
[16:40:06.120] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:06.917] INFO: Expecting 41600 events.
[16:40:10.357] INFO: 41600 events read in total (2913ms).
[16:40:10.361] INFO: Test took 4241ms.
[16:40:10.486] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:10.486] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 708552
[16:40:10.486] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:40:10.487] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:10.542] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:11.209] INFO: Expecting 41600 events.
[16:40:14.762] INFO: 41600 events read in total (3027ms).
[16:40:14.767] INFO: Test took 4225ms.
[16:40:14.887] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:14.887] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719134
[16:40:14.887] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:40:14.888] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:14.943] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:15.610] INFO: Expecting 41600 events.
[16:40:19.212] INFO: 41600 events read in total (3077ms).
[16:40:19.217] INFO: Test took 4274ms.
[16:40:19.339] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:19.339] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 722111
[16:40:19.339] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:40:19.339] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:19.394] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:20.059] INFO: Expecting 41600 events.
[16:40:23.675] INFO: 41600 events read in total (3089ms).
[16:40:23.680] INFO: Test took 4286ms.
[16:40:23.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:23.803] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 720679
[16:40:23.803] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:40:23.804] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:23.860] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:24.526] INFO: Expecting 41600 events.
[16:40:28.154] INFO: 41600 events read in total (3101ms).
[16:40:28.158] INFO: Test took 4298ms.
[16:40:28.279] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:28.279] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 722674
[16:40:28.279] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:40:28.279] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:28.334] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:28.998] INFO: Expecting 41600 events.
[16:40:32.625] INFO: 41600 events read in total (3100ms).
[16:40:32.629] INFO: Test took 4295ms.
[16:40:32.752] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:32.752] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 723469
[16:40:32.752] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:40:32.753] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:32.809] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:33.476] INFO: Expecting 41600 events.
[16:40:37.113] INFO: 41600 events read in total (3111ms).
[16:40:37.118] INFO: Test took 4309ms.
[16:40:37.241] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:37.241] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 726081
[16:40:37.241] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:40:37.241] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:37.300] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:37.965] INFO: Expecting 41600 events.
[16:40:41.602] INFO: 41600 events read in total (3110ms).
[16:40:41.607] INFO: Test took 4307ms.
[16:40:41.730] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:41.730] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 724262
[16:40:41.730] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:40:41.730] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:41.787] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:42.453] INFO: Expecting 41600 events.
[16:40:46.100] INFO: 41600 events read in total (3120ms).
[16:40:46.105] INFO: Test took 4318ms.
[16:40:46.227] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:46.227] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 724261
[16:40:46.227] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:40:46.227] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:46.283] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:46.950] INFO: Expecting 41600 events.
[16:40:50.596] INFO: 41600 events read in total (3119ms).
[16:40:50.601] INFO: Test took 4317ms.
[16:40:50.723] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:50.723] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 724261
[16:40:50.723] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:40:50.723] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:50.780] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:51.446] INFO: Expecting 41600 events.
[16:40:55.070] INFO: 41600 events read in total (3097ms).
[16:40:55.075] INFO: Test took 4295ms.
[16:40:55.196] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:55.196] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 723496
[16:40:55.196] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:40:55.196] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:55.252] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:40:55.917] INFO: Expecting 41600 events.
[16:40:59.547] INFO: 41600 events read in total (3104ms).
[16:40:59.551] INFO: Test took 4299ms.
[16:40:59.672] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:59.672] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 723800
[16:40:59.672] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:40:59.673] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:40:59.727] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:41:00.392] INFO: Expecting 41600 events.
[16:41:04.054] INFO: 41600 events read in total (3135ms).
[16:41:04.059] INFO: Test took 4332ms.
[16:41:04.180] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:04.181] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 723556
[16:41:04.181] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:41:04.181] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:41:04.235] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:41:04.904] INFO: Expecting 41600 events.
[16:41:08.520] INFO: 41600 events read in total (3090ms).
[16:41:08.525] INFO: Test took 4289ms.
[16:41:08.647] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:08.647] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 723720
[16:41:08.647] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:41:08.647] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:41:08.702] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:41:09.367] INFO: Expecting 41600 events.
[16:41:13.013] INFO: 41600 events read in total (3119ms).
[16:41:13.018] INFO: Test took 4316ms.
[16:41:13.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:13.141] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 725183
[16:41:13.141] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:41:13.142] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:41:13.199] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:41:13.865] INFO: Expecting 41600 events.
[16:41:17.511] INFO: 41600 events read in total (3119ms).
[16:41:17.516] INFO: Test took 4317ms.
[16:41:17.640] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:17.640] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 723880
[16:41:17.640] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:41:17.640] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:41:17.697] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:41:18.363] INFO: Expecting 41600 events.
[16:41:22.005] INFO: 41600 events read in total (3115ms).
[16:41:22.010] INFO: Test took 4313ms.
[16:41:22.129] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:22.129] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 722697
[16:41:22.130] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:41:22.130] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:41:22.184] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:41:22.849] INFO: Expecting 41600 events.
[16:41:26.494] INFO: 41600 events read in total (3118ms).
[16:41:26.498] INFO: Test took 4313ms.
[16:41:26.620] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:26.620] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 722100
[16:41:26.620] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:41:26.621] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:41:26.677] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:41:27.344] INFO: Expecting 41600 events.
[16:41:30.960] INFO: 41600 events read in total (3090ms).
[16:41:30.965] INFO: Test took 4288ms.
[16:41:31.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:31.085] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 722341
[16:41:31.085] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:41:31.086] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:41:31.140] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:41:31.805] INFO: Expecting 41600 events.
[16:41:35.257] INFO: 41600 events read in total (2925ms).
[16:41:35.262] INFO: Test took 4122ms.
[16:41:35.382] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:35.382] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 716218
[16:41:35.382] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:41:35.382] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:41:35.661] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 150 eff = 0.999159
[16:41:35.661] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 148 eff = 0.999423
[16:41:35.661] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 151 eff = 0.998534
[16:41:35.661] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 169 eff = 0.997452
[16:41:35.662] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 171 eff = 0.9975
[16:41:35.662] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 163 eff = 0.997139
[16:41:35.662] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 148 eff = 0.997548
[16:41:35.662] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 161 eff = 0.998341
[16:41:35.662] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 155 eff = 0.997668
[16:41:35.663] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 160 eff = 0.997188
[16:41:35.663] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 161 eff = 0.997188
[16:41:35.663] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 158 eff = 0.997428
[16:41:35.663] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 157 eff = 0.997957
[16:41:35.664] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 148 eff = 0.99851
[16:41:35.664] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 146 eff = 0.999567
[16:41:35.664] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 164 eff = 0.999279
[16:41:42.822] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:41:42.822] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:41:42.822] INFO: ----------------------------------------------------------------------
[16:41:42.822] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:41:42.822] INFO: ----------------------------------------------------------------------
[16:41:42.823] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:41:42.823] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:41:42.823] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:41:42.823] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:41:42.823] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:41:42.823] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:41:42.823] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:41:42.823] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:41:42.823] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:41:42.823] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:41:42.823] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:41:42.823] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:41:42.842] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:41:43.511] INFO: Expecting 208000 events.
[16:41:58.675] INFO: 208000 events read in total (14637ms).
[16:41:58.692] INFO: Test took 15850ms.
[16:41:59.269] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:59.269] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3319533
[16:41:59.269] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:41:59.269] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:41:59.716] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:41:59.716] INFO: number of red-efficiency pixels: 242 119 291 452 504 536 493 452 424 568 542 497 481 343 133 195
[16:41:59.716] INFO: number of X-ray hits detected: 139844 93441 158908 243038 257138 252746 252242 224599 230053 263855 263501 245401 248391 161120 95985 122711
[16:41:59.716] 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:59.716] INFO: number of Vcal hits detected: 207748 207878 207688 207509 207435 207434 207459 207509 207536 207369 207402 207451 207479 207616 207864 207789
[16:41:59.716] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.8 99.8 99.8 99.7 99.7 99.8 99.8 99.8 99.9 99.9
[16:41:59.716] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.7 99.8 99.8 99.7 99.7 99.7 99.7 99.8 99.9 99.9
[16:41:59.716] INFO: X-ray hit rate [MHz/cm2]: 41.0 27.4 46.6 71.2 75.4 74.1 73.9 65.8 67.4 77.3 77.2 71.9 72.8 47.2 28.1 36.0
[16:41:59.716] INFO: PixTestHighRate::doXPixelAlive() done
[16:41:59.763] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:41:59.763] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:41:59.763] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:41:59.763] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:41:59.763] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:41:59.763] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:41:59.763] INFO: PixTest:: pg_setup set to default.
[16:42:32.670] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:42:32.670] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[16:42:34.126] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:42:34.127] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 388.3mA
[16:42:34.128] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 461.4mA
[16:42:34.128] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:42:34.128] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:42:34.128] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:42:34.128] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:42:34.128] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:42:34.128] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:42:34.128] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:42:34.128] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:42:34.128] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:42:34.128] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:42:34.128] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:42:34.219] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:42:34.219] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:42:34.229] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:42:34.229] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:42:34.229] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:42:34.229] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:42:34.229] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:42:34.229] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:42:34.232] QUIET: Connection to board 43 closed.
[16:42:34.312] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[16:36:43.176] INFO: *** Welcome to pxar ***
[16:36:43.176] INFO: *** Today: 2016/06/16
[16:36:43.203] INFO: *** Version: v1.9.0-814-g7497
[16:36:43.203] INFO: readRocDacs: data/mp237/dacParameters35_C0.dat .. data/mp237/dacParameters35_C15.dat
[16:36:43.204] INFO: readTbmDacs: data/mp237/tbmParameters_C0a.dat .. data/mp237/tbmParameters_C0b.dat
[16:36:43.204] INFO: readMaskFile: data/mp237/defaultMaskFile.dat
[16:36:43.205] INFO: readTrimFile: data/mp237/trimParameters35_C0.dat .. data/mp237/trimParameters35_C15.dat
[16:36:43.356] INFO: clk: 4
[16:36:43.356] INFO: ctr: 4
[16:36:43.356] INFO: sda: 19
[16:36:43.356] INFO: tin: 9
[16:36:43.357] INFO: level: 15
[16:36:43.357] INFO: triggerdelay: 0
[16:36:43.357] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:36:43.357] INFO: Log level: DEBUG
[16:36:43.367] QUIET: Connection to board DTB_WRMNMR opened.
[16:36:43.371] 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:36:43.373] INFO: RPC call hashes of host and DTB match: 398089610
[16:36:44.976] INFO: DUT info:
[16:36:44.976] INFO: The DUT currently contains the following objects:
[16:36:44.976] INFO: 2 TBM Cores tbm08c (2 ON)
[16:36:44.976] INFO: TBM Core alpha (0): 7 registers set
[16:36:44.976] INFO: TBM Core beta (1): 7 registers set
[16:36:44.976] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:36:44.976] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.976] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.976] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.976] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.976] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.976] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.976] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.976] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.976] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.976] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.976] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.976] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.976] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.976] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.976] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.976] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:36:44.977] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:36:44.978] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:36:44.979] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:36:44.980] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:36:44.982] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33259520
[16:36:44.982] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x24b9bd0
[16:36:44.982] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x225be60
[16:36:44.982] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fcc29d94010
[16:36:44.982] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fcc2ffff510
[16:36:44.982] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33267712 fPxarMemory = 0x7fcc29d94010
[16:36:44.983] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 384.3mA
[16:36:44.985] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 460.6mA
[16:36:44.985] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:36:45.481] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x28a4970
[16:36:45.548] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:36:45.548] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:36:45.548] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:36:45.554] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:36:45.554] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:36:45.554] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:36:45.564] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:36:45.565] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:36:45.579] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:36:45.579] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:36:45.596] INFO: PixTestCmd::init()
[16:36:45.612] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:36:45.612] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:36:45.612] INFO: readGainPedestalParameters data/mp237/phCalibrationFitErr35_C0.dat .. data/mp237/phCalibrationFitErr35_C15.dat
[16:36:45.855] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:36:45.855] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:36:45.873] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:36:45.879] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:36:45.879] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:36:45.879] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:36:45.905] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:36:45.905] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:36:45.905] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:36:45.905] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:36:45.905] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:36:45.912] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:36:45.912] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:36:45.933] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:36:45.933] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:36:45.933] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:36:45.933] INFO: readReadbackCal: data/mp237/readbackCal_C0.dat .. data/mp237/readbackCal_C15.dat
[16:36:45.943] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:36:45.959] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:36:45.959] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:36:45.959] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:36:45.959] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:36:45.972] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:36:45.972] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:36:51.001] INFO: ######################################################################
[16:36:51.001] INFO: PixTestAlive::doTest()
[16:36:51.001] INFO: ######################################################################
[16:36:51.004] INFO: ----------------------------------------------------------------------
[16:36:51.005] 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:36:51.005] INFO: ----------------------------------------------------------------------
[16:36:51.007] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:36:51.386] INFO: Expecting 41600 events.
[16:36:55.591] INFO: 41600 events read in total (3487ms).
[16:36:55.738] INFO: Test took 4731ms.
[16:36:55.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:55.748] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:36:55.748] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:36:56.025] INFO: PixTestAlive::aliveTest() done
[16:36:56.025] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:36:56.025] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 58 29 50 84 79 83 80 78 81 90 92 84 88 50 23 45
[16:36:56.052] INFO: ----------------------------------------------------------------------
[16:36:56.052] 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:36:56.052] INFO: ----------------------------------------------------------------------
[16:36:56.055] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:36:56.432] INFO: Expecting 41600 events.
[16:36:59.371] INFO: 41600 events read in total (2223ms).
[16:36:59.371] INFO: Test took 3316ms.
[16:36:59.371] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:59.371] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:36:59.371] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:36:59.372] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:36:59.793] INFO: PixTestAlive::maskTest() done
[16:36:59.793] 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:36:59.816] INFO: ----------------------------------------------------------------------
[16:36:59.816] 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:36:59.816] INFO: ----------------------------------------------------------------------
[16:36:59.818] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:37:00.195] INFO: Expecting 41600 events.
[16:37:04.395] INFO: 41600 events read in total (3485ms).
[16:37:04.397] INFO: Test took 4579ms.
[16:37:04.408] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:04.408] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[16:37:04.408] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:37:04.777] INFO: PixTestAlive::addressDecodingTest() done
[16:37:04.777] 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:37:04.778] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[16:37:10.734] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:37:10.734] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:37:10.734] INFO: ----------------------------------------------------------------------
[16:37:10.734] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:37:10.734] INFO: ----------------------------------------------------------------------
[16:37:10.873] INFO: Expecting 768 events.
[16:37:12.002] INFO: 768 events read in total (413ms).
[16:37:12.003] INFO: Test took 1263ms.
[16:37:12.008] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:37:12.806] INFO: Expecting 41600 events.
[16:37:16.538] INFO: 41600 events read in total (3206ms).
[16:37:16.543] INFO: Test took 4535ms.
[16:37:16.701] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:16.701] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 928215
[16:37:16.701] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:37:16.701] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:37:16.769] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:37:17.436] INFO: Expecting 41600 events.
[16:37:21.282] INFO: 41600 events read in total (3320ms).
[16:37:21.288] INFO: Test took 4519ms.
[16:37:21.440] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:21.440] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 937912
[16:37:21.440] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:37:21.440] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:37:21.511] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:37:22.178] INFO: Expecting 41600 events.
[16:37:26.077] INFO: 41600 events read in total (3372ms).
[16:37:26.082] INFO: Test took 4571ms.
[16:37:26.237] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:26.237] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 943075
[16:37:26.237] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:37:26.238] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:37:26.308] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:37:26.973] INFO: Expecting 41600 events.
[16:37:30.870] INFO: 41600 events read in total (3370ms).
[16:37:30.876] INFO: Test took 4568ms.
[16:37:31.028] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:31.028] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 941567
[16:37:31.028] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:37:31.029] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:37:31.096] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:37:31.761] INFO: Expecting 41600 events.
[16:37:35.655] INFO: 41600 events read in total (3367ms).
[16:37:35.661] INFO: Test took 4565ms.
[16:37:35.815] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:35.815] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 942317
[16:37:35.815] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:37:35.815] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:37:35.887] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:37:36.552] INFO: Expecting 41600 events.
[16:37:40.476] INFO: 41600 events read in total (3397ms).
[16:37:40.482] INFO: Test took 4595ms.
[16:37:40.633] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:40.633] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 942407
[16:37:40.633] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:37:40.634] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:37:40.704] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:37:41.369] INFO: Expecting 41600 events.
[16:37:45.316] INFO: 41600 events read in total (3420ms).
[16:37:45.321] INFO: Test took 4617ms.
[16:37:45.472] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:45.472] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 942919
[16:37:45.472] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:37:45.473] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:37:45.543] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:37:46.208] INFO: Expecting 41600 events.
[16:37:50.162] INFO: 41600 events read in total (3427ms).
[16:37:50.167] INFO: Test took 4624ms.
[16:37:50.324] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:50.325] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 941871
[16:37:50.325] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:37:50.325] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:37:50.397] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:37:51.063] INFO: Expecting 41600 events.
[16:37:54.988] INFO: 41600 events read in total (3398ms).
[16:37:54.994] INFO: Test took 4597ms.
[16:37:55.148] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:55.148] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 942267
[16:37:55.148] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:37:55.148] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:37:55.220] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:37:55.886] INFO: Expecting 41600 events.
[16:37:59.840] INFO: 41600 events read in total (3427ms).
[16:37:59.845] INFO: Test took 4625ms.
[16:37:59.999] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:59.999] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 942339
[16:37:59.999] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:37:59.999] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:38:00.070] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:38:00.735] INFO: Expecting 41600 events.
[16:38:04.668] INFO: 41600 events read in total (3407ms).
[16:38:04.674] INFO: Test took 4604ms.
[16:38:04.826] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:04.826] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 940272
[16:38:04.826] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:38:04.826] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:38:04.896] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:38:05.561] INFO: Expecting 41600 events.
[16:38:09.499] INFO: 41600 events read in total (3412ms).
[16:38:09.505] INFO: Test took 4609ms.
[16:38:09.661] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:09.661] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 940763
[16:38:09.661] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:38:09.662] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:38:09.731] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:38:10.396] INFO: Expecting 41600 events.
[16:38:14.329] INFO: 41600 events read in total (3406ms).
[16:38:14.334] INFO: Test took 4603ms.
[16:38:14.493] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:14.493] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 942453
[16:38:14.493] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:38:14.493] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:38:14.563] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:38:15.228] INFO: Expecting 41600 events.
[16:38:19.159] INFO: 41600 events read in total (3405ms).
[16:38:19.164] INFO: Test took 4601ms.
[16:38:19.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:19.319] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 939416
[16:38:19.319] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:38:19.320] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:38:19.389] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:38:20.056] INFO: Expecting 41600 events.
[16:38:24.007] INFO: 41600 events read in total (3425ms).
[16:38:24.013] INFO: Test took 4624ms.
[16:38:24.170] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:24.170] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 941846
[16:38:24.170] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:38:24.170] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:38:24.237] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:38:24.902] INFO: Expecting 41600 events.
[16:38:28.826] INFO: 41600 events read in total (3397ms).
[16:38:28.831] INFO: Test took 4594ms.
[16:38:28.986] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:28.986] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 940880
[16:38:28.986] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:38:28.987] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:38:29.054] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:38:29.719] INFO: Expecting 41600 events.
[16:38:33.666] INFO: 41600 events read in total (3420ms).
[16:38:33.672] INFO: Test took 4618ms.
[16:38:33.828] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:33.828] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 940488
[16:38:33.828] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:38:33.829] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:38:33.895] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:38:34.560] INFO: Expecting 41600 events.
[16:38:38.490] INFO: 41600 events read in total (3403ms).
[16:38:38.495] INFO: Test took 4600ms.
[16:38:38.651] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:38.651] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 940879
[16:38:38.651] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:38:38.652] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:38:38.720] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:38:39.384] INFO: Expecting 41600 events.
[16:38:44.375] INFO: 41600 events read in total (4464ms).
[16:38:44.381] INFO: Test took 5661ms.
[16:38:44.535] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:44.535] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 938601
[16:38:44.536] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:38:44.536] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:38:44.604] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:38:45.269] INFO: Expecting 41600 events.
[16:38:49.002] INFO: 41600 events read in total (3206ms).
[16:38:49.007] INFO: Test took 4403ms.
[16:38:49.159] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:49.159] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 932162
[16:38:49.159] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:38:49.159] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:38:49.449] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 159 eff = 0.998245
[16:38:49.449] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 129 eff = 0.999087
[16:38:49.449] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 151 eff = 0.997308
[16:38:49.449] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 172 eff = 0.995745
[16:38:49.449] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 168 eff = 0.995361
[16:38:49.449] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 159 eff = 0.994567
[16:38:49.450] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 147 eff = 0.995
[16:38:49.450] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 164 eff = 0.995841
[16:38:49.450] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 155 eff = 0.995577
[16:38:49.450] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 161 eff = 0.993846
[16:38:49.450] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 163 eff = 0.994519
[16:38:49.450] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 156 eff = 0.994952
[16:38:49.450] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 158 eff = 0.994952
[16:38:49.451] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 164 eff = 0.997428
[16:38:49.451] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 148 eff = 0.999207
[16:38:49.451] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 155 eff = 0.998726
[16:38:59.870] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:38:59.870] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:38:59.870] INFO: ----------------------------------------------------------------------
[16:38:59.870] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:38:59.870] INFO: ----------------------------------------------------------------------
[16:38:59.871] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:38:59.871] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:38:59.871] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:38:59.871] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:38:59.871] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:38:59.871] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:38:59.871] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:38:59.871] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:38:59.871] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:38:59.871] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:38:59.871] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:38:59.871] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:38:59.888] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:39:00.558] INFO: Expecting 208000 events.
[16:39:17.775] INFO: 208000 events read in total (16690ms).
[16:39:17.797] INFO: Test took 17909ms.
[16:39:18.721] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:18.721] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4390561
[16:39:18.721] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:39:18.721] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:39:19.238] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:39:19.238] INFO: number of red-efficiency pixels: 352 247 451 772 858 893 827 795 741 1036 982 864 808 417 186 315
[16:39:19.238] INFO: number of X-ray hits detected: 186016 124918 211175 323189 340547 335609 335139 299525 307182 350727 350217 326723 328556 213323 127809 163346
[16:39:19.238] 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:39:19.238] INFO: number of Vcal hits detected: 207624 207736 207459 207123 206987 206917 207037 207075 207145 206747 206803 206968 207060 207530 207808 207660
[16:39:19.238] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.6 99.6 99.5 99.6 99.6 99.6 99.5 99.5 99.6 99.6 99.8 99.9 99.8
[16:39:19.238] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.5 99.5 99.5 99.6 99.6 99.4 99.4 99.5 99.5 99.8 99.9 99.8
[16:39:19.238] INFO: X-ray hit rate [MHz/cm2]: 54.5 36.6 61.9 94.7 99.8 98.4 98.2 87.8 90.0 102.8 102.7 95.8 96.3 62.5 37.5 47.9
[16:39:19.238] INFO: PixTestHighRate::doXPixelAlive() done
[16:39:19.283] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:39:19.283] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:39:19.283] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:39:19.283] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:39:19.283] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:39:19.283] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:39:19.283] INFO: PixTest:: pg_setup set to default.
[16:39:31.398] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:39:31.398] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[16:39:33.086] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:39:33.087] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 387.5mA
[16:39:33.088] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 462.3mA
[16:39:33.088] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:39:33.088] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:39:33.088] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:39:33.088] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:39:33.088] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:39:33.088] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:39:33.088] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:39:33.088] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:39:33.088] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:39:33.088] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:39:33.088] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:39:33.182] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:39:33.182] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:39:33.191] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:39:33.192] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:39:33.192] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:39:33.192] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:39:33.192] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:39:33.192] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:39:33.194] QUIET: Connection to board 43 closed.
[16:39:33.274] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master