Test Date: 1973-11-29 16:33
Analysis date: 2016-06-23 15:23
Logfile
hrData_40.log
[15:14:41.231] INFO: *** Welcome to pxar ***
[15:14:41.231] INFO: *** Today: 2016/06/20
[15:14:41.260] INFO: *** Version: v1.9.0-814-g7497
[15:14:41.260] INFO: readRocDacs: data/mn302/dacParameters35_C0.dat .. data/mn302/dacParameters35_C15.dat
[15:14:41.261] INFO: readTbmDacs: data/mn302/tbmParameters_C0a.dat .. data/mn302/tbmParameters_C0b.dat
[15:14:41.261] INFO: readMaskFile: data/mn302/defaultMaskFile.dat
[15:14:41.261] INFO: readTrimFile: data/mn302/trimParameters35_C0.dat .. data/mn302/trimParameters35_C15.dat
[15:14:41.411] INFO: clk: 4
[15:14:41.411] INFO: ctr: 4
[15:14:41.411] INFO: sda: 19
[15:14:41.411] INFO: tin: 9
[15:14:41.411] INFO: level: 15
[15:14:41.411] INFO: triggerdelay: 0
[15:14:41.411] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:14:41.411] INFO: Log level: DEBUG
[15:14:41.421] QUIET: Connection to board DTB_WRPRHI opened.
[15:14:41.425] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:14:41.427] INFO: RPC call hashes of host and DTB match: 398089610
[15:14:43.029] INFO: DUT info:
[15:14:43.029] INFO: The DUT currently contains the following objects:
[15:14:43.029] INFO: 2 TBM Cores tbm08c (2 ON)
[15:14:43.029] INFO: TBM Core alpha (0): 7 registers set
[15:14:43.029] INFO: TBM Core beta (1): 7 registers set
[15:14:43.029] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:14:43.029] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.029] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.029] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.029] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.029] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.029] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.029] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.029] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.029] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.029] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.029] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.029] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.029] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.029] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.030] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.030] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:14:43.030] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:14:43.031] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:14:43.032] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:14:43.033] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:14:43.035] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32870400
[15:14:43.035] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x248f110
[15:14:43.035] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x241d360
[15:14:43.035] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f9859d94010
[15:14:43.035] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f985ffff510
[15:14:43.035] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32878592 fPxarMemory = 0x7f9859d94010
[15:14:43.037] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 376.2mA
[15:14:43.038] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 475.1mA
[15:14:43.038] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:14:43.533] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2a65db0
[15:14:43.595] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:14:43.595] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:14:43.595] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:14:43.600] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:14:43.600] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:14:43.601] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:14:43.611] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:14:43.611] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:14:43.629] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:14:43.629] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:14:43.645] INFO: PixTestCmd::init()
[15:14:43.652] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:14:43.652] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:14:43.652] INFO: readGainPedestalParameters data/mn302/phCalibrationFitErr35_C0.dat .. data/mn302/phCalibrationFitErr35_C15.dat
[15:14:43.893] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:14:43.893] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:14:43.906] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:14:43.913] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:14:43.913] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:14:43.913] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:14:43.933] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:14:43.933] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:14:43.933] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:14:43.933] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:14:43.933] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:14:43.939] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:14:43.939] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:14:43.955] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:14:43.955] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:14:43.955] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:14:43.955] INFO: readReadbackCal: data/mn302/readbackCal_C0.dat .. data/mn302/readbackCal_C15.dat
[15:14:43.965] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:14:43.973] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:14:43.973] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:14:43.973] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:14:43.973] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:14:43.985] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:14:43.985] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:14:49.981] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[15:14:51.693] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[15:14:53.042] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[15:14:53.042] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[15:14:53.042] INFO: ----------------------------------------------------------------------
[15:14:53.042] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[15:14:53.042] INFO: ----------------------------------------------------------------------
[15:14:54.016] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[15:15:07.167] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[15:15:07.171] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:15:34.611] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1295683 events.
[15:15:39.787] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1295683, pixels seen in all events: 4703448
[15:15:39.888] INFO: Resuming triggers.
[15:15:53.038] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[15:15:53.074] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:16:20.672] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1295445 events.
[15:16:25.427] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1295445, pixels seen in all events: 4705828
[15:16:25.575] INFO: Resuming triggers.
[15:16:38.723] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[15:16:38.816] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:17:06.277] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1295297 events.
[15:17:11.030] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1295297, pixels seen in all events: 4707298
[15:17:11.241] INFO: Resuming triggers.
[15:17:24.391] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[15:17:24.427] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:17:51.819] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1295407 events.
[15:17:56.633] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1295407, pixels seen in all events: 4706140
[15:17:56.728] INFO: Resuming triggers.
[15:18:04.212] INFO: data taking finished, elapsed time: 60 seconds.
[15:18:04.408] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:18:20.100] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 737283 events.
[15:18:22.786] DEBUG: <PixTestXray.cc/processData:L823> # events read: 737283, pixels seen in all events: 2680038
[15:18:22.856] INFO: PixTest:: pg_setup set to default.
[15:18:22.929] INFO: PixTestXray::doPhRun() done
[15:18:56.386] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:18:56.387] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 377mA
[15:18:56.388] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 475.1mA
[15:18:56.388] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:18:56.388] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:18:56.388] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:18:56.388] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:18:56.388] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:18:56.388] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:18:56.388] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:18:56.388] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:18:56.388] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:18:56.388] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:18:56.389] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:18:56.389] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:18:56.389] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:18:56.389] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:18:56.389] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:18:56.389] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:18:56.389] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:18:56.389] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:18:56.390] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:18:56.676] QUIET: Connection to board 58 closed.
[15:18:56.756] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[15:19:02.413] INFO: *** Welcome to pxar ***
[15:19:02.413] INFO: *** Today: 2016/06/20
[15:19:02.439] INFO: *** Version: v1.9.0-814-g7497
[15:19:02.439] INFO: readRocDacs: data/mn302/dacParameters35_C0.dat .. data/mn302/dacParameters35_C15.dat
[15:19:02.440] INFO: readTbmDacs: data/mn302/tbmParameters_C0a.dat .. data/mn302/tbmParameters_C0b.dat
[15:19:02.440] INFO: readMaskFile: data/mn302/defaultMaskFile.dat
[15:19:02.440] INFO: readTrimFile: data/mn302/trimParameters35_C0.dat .. data/mn302/trimParameters35_C15.dat
[15:19:02.589] INFO: clk: 4
[15:19:02.589] INFO: ctr: 4
[15:19:02.589] INFO: sda: 19
[15:19:02.589] INFO: tin: 9
[15:19:02.589] INFO: level: 15
[15:19:02.589] INFO: triggerdelay: 0
[15:19:02.589] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:19:02.589] INFO: Log level: DEBUG
[15:19:02.600] QUIET: Connection to board DTB_WRPRHI opened.
[15:19:02.603] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:19:02.606] INFO: RPC call hashes of host and DTB match: 398089610
[15:19:04.208] INFO: DUT info:
[15:19:04.209] INFO: The DUT currently contains the following objects:
[15:19:04.209] INFO: 2 TBM Cores tbm08c (2 ON)
[15:19:04.209] INFO: TBM Core alpha (0): 7 registers set
[15:19:04.209] INFO: TBM Core beta (1): 7 registers set
[15:19:04.209] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:19:04.209] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.209] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.209] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.209] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.209] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.209] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.209] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.209] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.209] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.209] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.209] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.209] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.209] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.209] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.209] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.209] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:19:04.210] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:19:04.211] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:19:04.212] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:19:04.213] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:19:04.215] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33542144
[15:19:04.215] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2623110
[15:19:04.215] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x25b1360
[15:19:04.215] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fad61d94010
[15:19:04.215] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fad67fff510
[15:19:04.215] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33550336 fPxarMemory = 0x7fad61d94010
[15:19:04.216] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 375.4mA
[15:19:04.217] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 475.1mA
[15:19:04.217] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.7 C
[15:19:04.714] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2bf9e10
[15:19:04.777] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:19:04.777] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:19:04.777] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:19:04.782] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:19:04.782] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:19:04.782] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:19:04.791] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:19:04.791] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:19:04.813] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:19:04.813] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:19:04.825] INFO: PixTestCmd::init()
[15:19:04.829] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:19:04.829] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:19:04.829] INFO: readGainPedestalParameters data/mn302/phCalibrationFitErr35_C0.dat .. data/mn302/phCalibrationFitErr35_C15.dat
[15:19:05.075] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:19:05.075] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:19:05.086] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:19:05.094] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:19:05.094] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:19:05.094] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:19:05.115] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:19:05.115] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:19:05.115] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:19:05.115] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:19:05.115] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:19:05.120] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:19:05.120] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:19:05.132] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:19:05.132] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:19:05.132] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:19:05.132] INFO: readReadbackCal: data/mn302/readbackCal_C0.dat .. data/mn302/readbackCal_C15.dat
[15:19:05.142] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:19:05.151] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:19:05.151] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:19:05.151] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:19:05.151] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:19:05.164] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:19:05.164] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:19:10.973] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[15:19:12.797] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[15:20:35.658] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[15:20:35.658] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[15:20:35.658] INFO: ----------------------------------------------------------------------
[15:20:35.658] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[15:20:35.658] INFO: ----------------------------------------------------------------------
[15:20:36.632] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[15:20:44.826] INFO: run duration 8 seconds, buffer almost full (81%), pausing triggers.
[15:20:44.830] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:21:10.053] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 807376 events.
[15:21:22.016] DEBUG: <PixTestXray.cc/processData:L823> # events read: 807376, pixels seen in all events: 10564684
[15:21:22.278] INFO: Resuming triggers.
[15:21:30.476] INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[15:21:30.507] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:21:55.662] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 807609 events.
[15:22:07.616] DEBUG: <PixTestXray.cc/processData:L823> # events read: 807609, pixels seen in all events: 10560520
[15:22:07.787] INFO: Resuming triggers.
[15:22:15.981] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[15:22:16.090] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:22:41.368] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 807203 events.
[15:22:53.381] DEBUG: <PixTestXray.cc/processData:L823> # events read: 807203, pixels seen in all events: 10565275
[15:22:53.630] INFO: Resuming triggers.
[15:23:01.825] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[15:23:01.863] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:23:27.081] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 807347 events.
[15:23:39.137] DEBUG: <PixTestXray.cc/processData:L823> # events read: 807347, pixels seen in all events: 10563082
[15:23:39.389] INFO: Resuming triggers.
[15:23:47.583] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[15:23:47.619] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:24:12.901] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 807262 events.
[15:24:24.916] DEBUG: <PixTestXray.cc/processData:L823> # events read: 807262, pixels seen in all events: 10564257
[15:24:25.177] INFO: Resuming triggers.
[15:24:33.372] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[15:24:33.403] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:24:58.792] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 807283 events.
[15:25:10.763] DEBUG: <PixTestXray.cc/processData:L823> # events read: 807283, pixels seen in all events: 10563950
[15:25:11.009] INFO: Resuming triggers.
[15:25:19.206] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[15:25:19.238] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:25:44.418] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 807473 events.
[15:25:56.373] DEBUG: <PixTestXray.cc/processData:L823> # events read: 807473, pixels seen in all events: 10562675
[15:25:56.621] INFO: Resuming triggers.
[15:25:59.334] INFO: data taking finished, elapsed time: 60 seconds.
[15:25:59.531] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:26:08.058] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 267184 events.
[15:26:12.024] DEBUG: <PixTestXray.cc/processData:L823> # events read: 267184, pixels seen in all events: 3497089
[15:26:12.102] INFO: PixTest:: pg_setup set to default.
[15:26:12.144] INFO: PixTestXray::doPhRun() done
[15:27:53.626] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:27:53.627] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 377mA
[15:27:53.628] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 475.9mA
[15:27:53.628] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:27:53.628] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:27:53.628] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:27:53.628] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:27:53.628] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:27:53.628] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:27:53.628] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:27:53.628] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:27:53.628] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:27:53.628] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:27:53.629] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:27:53.629] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:27:53.629] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:27:53.629] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:27:53.629] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:27:53.629] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:27:53.629] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:27:53.629] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:27:53.629] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:27:53.933] QUIET: Connection to board 58 closed.
[15:27:54.013] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[15:08:33.616] INFO: *** Welcome to pxar ***
[15:08:33.616] INFO: *** Today: 2016/06/20
[15:08:33.643] INFO: *** Version: v1.9.0-814-g7497
[15:08:33.644] INFO: readRocDacs: data/mn302/dacParameters35_C0.dat .. data/mn302/dacParameters35_C15.dat
[15:08:33.645] INFO: readTbmDacs: data/mn302/tbmParameters_C0a.dat .. data/mn302/tbmParameters_C0b.dat
[15:08:33.645] INFO: readMaskFile: data/mn302/defaultMaskFile.dat
[15:08:33.645] INFO: readTrimFile: data/mn302/trimParameters35_C0.dat .. data/mn302/trimParameters35_C15.dat
[15:08:33.794] INFO: clk: 4
[15:08:33.794] INFO: ctr: 4
[15:08:33.794] INFO: sda: 19
[15:08:33.794] INFO: tin: 9
[15:08:33.794] INFO: level: 15
[15:08:33.794] INFO: triggerdelay: 0
[15:08:33.794] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:08:33.794] INFO: Log level: DEBUG
[15:08:33.805] QUIET: Connection to board DTB_WRPRHI opened.
[15:08:33.808] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:08:33.811] INFO: RPC call hashes of host and DTB match: 398089610
[15:08:35.412] INFO: DUT info:
[15:08:35.412] INFO: The DUT currently contains the following objects:
[15:08:35.412] INFO: 2 TBM Cores tbm08c (2 ON)
[15:08:35.412] INFO: TBM Core alpha (0): 7 registers set
[15:08:35.412] INFO: TBM Core beta (1): 7 registers set
[15:08:35.412] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:08:35.412] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.412] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.412] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.412] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.412] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.412] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.412] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.412] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.412] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.412] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.412] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.412] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.413] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.413] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.413] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.413] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:08:35.413] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:08:35.414] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:08:35.415] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:08:35.416] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:08:35.418] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32890880
[15:08:35.418] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x11f7110
[15:08:35.418] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1185360
[15:08:35.418] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f5ca1d94010
[15:08:35.418] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f5ca7fff510
[15:08:35.418] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32899072 fPxarMemory = 0x7f5ca1d94010
[15:08:35.419] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 375.4mA
[15:08:35.421] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 475.9mA
[15:08:35.421] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:08:35.913] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x17cded0
[15:08:35.976] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:08:35.976] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:08:35.976] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:08:35.981] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:08:35.982] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:08:35.982] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:08:35.991] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:08:35.991] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:08:36.003] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:08:36.003] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:08:36.021] INFO: PixTestCmd::init()
[15:08:36.028] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:08:36.028] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:08:36.028] INFO: readGainPedestalParameters data/mn302/phCalibrationFitErr35_C0.dat .. data/mn302/phCalibrationFitErr35_C15.dat
[15:08:36.269] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:08:36.269] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:08:36.281] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:08:36.288] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:08:36.288] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:08:36.288] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:08:36.308] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:08:36.308] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:08:36.308] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:08:36.308] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:08:36.308] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:08:36.314] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:08:36.314] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:08:36.330] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:08:36.330] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:08:36.330] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:08:36.330] INFO: readReadbackCal: data/mn302/readbackCal_C0.dat .. data/mn302/readbackCal_C15.dat
[15:08:36.339] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:08:36.346] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:08:36.346] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:08:36.346] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:08:36.346] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:08:36.357] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:08:36.357] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:08:39.533] INFO: ######################################################################
[15:08:39.533] INFO: PixTestAlive::doTest()
[15:08:39.533] INFO: ######################################################################
[15:08:39.536] INFO: ----------------------------------------------------------------------
[15:08:39.536] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:08:39.536] INFO: ----------------------------------------------------------------------
[15:08:39.539] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:08:39.917] INFO: Expecting 41600 events.
[15:08:44.302] INFO: 41600 events read in total (3666ms).
[15:08:44.450] INFO: Test took 4911ms.
[15:08:44.460] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:44.460] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:08:44.460] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:08:44.729] INFO: PixTestAlive::aliveTest() done
[15:08:44.729] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[15:08:44.729] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 16 7 19 34 34 32 37 24 26 32 34 31 45 22 14 15
[15:08:44.751] INFO: ----------------------------------------------------------------------
[15:08:44.751] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:08:44.751] INFO: ----------------------------------------------------------------------
[15:08:44.754] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:08:45.132] INFO: Expecting 41600 events.
[15:08:48.255] INFO: 41600 events read in total (2408ms).
[15:08:48.255] INFO: Test took 3501ms.
[15:08:48.255] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:48.255] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:08:48.255] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:08:48.256] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:08:48.678] INFO: PixTestAlive::maskTest() done
[15:08:48.678] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:08:48.700] INFO: ----------------------------------------------------------------------
[15:08:48.700] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:08:48.700] INFO: ----------------------------------------------------------------------
[15:08:48.702] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:08:49.079] INFO: Expecting 41600 events.
[15:08:53.465] INFO: 41600 events read in total (3671ms).
[15:08:53.466] INFO: Test took 4764ms.
[15:08:53.477] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:53.478] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:08:53.478] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:08:53.847] INFO: PixTestAlive::addressDecodingTest() done
[15:08:53.847] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:08:53.848] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:09:02.002] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:09:02.002] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:09:02.002] INFO: ----------------------------------------------------------------------
[15:09:02.002] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:09:02.002] INFO: ----------------------------------------------------------------------
[15:09:02.141] INFO: Expecting 768 events.
[15:09:03.275] INFO: 768 events read in total (418ms).
[15:09:03.275] INFO: Test took 1267ms.
[15:09:03.281] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:04.079] INFO: Expecting 41600 events.
[15:09:07.325] INFO: 41600 events read in total (2720ms).
[15:09:07.329] INFO: Test took 4048ms.
[15:09:07.400] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:07.400] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 407338
[15:09:07.400] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:09:07.401] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:07.435] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:08.100] INFO: Expecting 41600 events.
[15:09:11.468] INFO: 41600 events read in total (2841ms).
[15:09:11.471] INFO: Test took 4036ms.
[15:09:11.541] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:11.541] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 415090
[15:09:11.541] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:09:11.541] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:11.576] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:12.241] INFO: Expecting 41600 events.
[15:09:15.649] INFO: 41600 events read in total (2881ms).
[15:09:15.652] INFO: Test took 4076ms.
[15:09:15.721] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:15.721] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 415983
[15:09:15.721] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:09:15.722] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:15.758] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:16.423] INFO: Expecting 41600 events.
[15:09:19.837] INFO: 41600 events read in total (2887ms).
[15:09:19.840] INFO: Test took 4082ms.
[15:09:19.910] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:19.910] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 416871
[15:09:19.910] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:09:19.910] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:19.946] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:20.611] INFO: Expecting 41600 events.
[15:09:24.030] INFO: 41600 events read in total (2892ms).
[15:09:24.033] INFO: Test took 4087ms.
[15:09:24.102] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:24.102] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 416577
[15:09:24.102] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:09:24.102] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:24.138] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:24.804] INFO: Expecting 41600 events.
[15:09:28.226] INFO: 41600 events read in total (2896ms).
[15:09:28.229] INFO: Test took 4091ms.
[15:09:28.299] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:28.299] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 416967
[15:09:28.299] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:09:28.299] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:28.335] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:29.001] INFO: Expecting 41600 events.
[15:09:32.427] INFO: 41600 events read in total (2900ms).
[15:09:32.430] INFO: Test took 4094ms.
[15:09:32.499] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:32.499] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 416459
[15:09:32.499] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:09:32.500] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:32.535] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:33.201] INFO: Expecting 41600 events.
[15:09:36.636] INFO: 41600 events read in total (2909ms).
[15:09:36.639] INFO: Test took 4103ms.
[15:09:36.709] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:36.709] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 417078
[15:09:36.709] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:09:36.710] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:36.746] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:37.411] INFO: Expecting 41600 events.
[15:09:40.843] INFO: 41600 events read in total (2905ms).
[15:09:40.846] INFO: Test took 4100ms.
[15:09:40.916] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:40.916] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 416231
[15:09:40.916] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:09:40.917] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:40.953] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:41.619] INFO: Expecting 41600 events.
[15:09:45.052] INFO: 41600 events read in total (2907ms).
[15:09:45.055] INFO: Test took 4102ms.
[15:09:45.124] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:45.124] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 417193
[15:09:45.124] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:09:45.125] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:45.161] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:45.826] INFO: Expecting 41600 events.
[15:09:49.250] INFO: 41600 events read in total (2897ms).
[15:09:49.254] INFO: Test took 4093ms.
[15:09:49.324] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:49.324] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 416006
[15:09:49.324] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:09:49.324] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:49.360] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:50.025] INFO: Expecting 41600 events.
[15:09:53.454] INFO: 41600 events read in total (2902ms).
[15:09:53.457] INFO: Test took 4097ms.
[15:09:53.526] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:53.526] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 416179
[15:09:53.526] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:09:53.526] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:53.562] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:54.227] INFO: Expecting 41600 events.
[15:09:57.660] INFO: 41600 events read in total (2907ms).
[15:09:57.663] INFO: Test took 4101ms.
[15:09:57.733] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:57.733] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 417308
[15:09:57.733] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:09:57.733] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:57.769] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:58.434] INFO: Expecting 41600 events.
[15:10:01.877] INFO: 41600 events read in total (2917ms).
[15:10:01.881] INFO: Test took 4112ms.
[15:10:01.950] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:01.950] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 418061
[15:10:01.950] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:10:01.950] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:01.987] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:02.652] INFO: Expecting 41600 events.
[15:10:06.082] INFO: 41600 events read in total (2903ms).
[15:10:06.086] INFO: Test took 4099ms.
[15:10:06.155] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:06.155] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 416670
[15:10:06.155] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:10:06.156] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:06.192] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:06.857] INFO: Expecting 41600 events.
[15:10:10.300] INFO: 41600 events read in total (2916ms).
[15:10:10.303] INFO: Test took 4111ms.
[15:10:10.372] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:10.372] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 415425
[15:10:10.372] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:10:10.372] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:10.408] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:11.073] INFO: Expecting 41600 events.
[15:10:14.509] INFO: 41600 events read in total (2909ms).
[15:10:14.512] INFO: Test took 4104ms.
[15:10:14.582] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:14.582] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 416763
[15:10:14.582] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:10:14.582] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:14.618] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:15.284] INFO: Expecting 41600 events.
[15:10:18.715] INFO: 41600 events read in total (2904ms).
[15:10:18.718] INFO: Test took 4099ms.
[15:10:18.787] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:18.787] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 416168
[15:10:18.787] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:10:18.787] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:18.823] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:19.488] INFO: Expecting 41600 events.
[15:10:22.912] INFO: 41600 events read in total (2897ms).
[15:10:22.916] INFO: Test took 4093ms.
[15:10:22.984] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:22.984] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 415579
[15:10:22.984] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:10:22.985] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:23.020] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:23.685] INFO: Expecting 41600 events.
[15:10:26.951] INFO: 41600 events read in total (2739ms).
[15:10:26.956] INFO: Test took 3936ms.
[15:10:27.024] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:27.024] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 408389
[15:10:27.024] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:10:27.024] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:27.281] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 153 eff = 0.999712
[15:10:27.281] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 145 eff = 0.99988
[15:10:27.282] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 179 eff = 0.999688
[15:10:27.282] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 160 eff = 0.999303
[15:10:27.282] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 157 eff = 0.999087
[15:10:27.282] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 136 eff = 0.999183
[15:10:27.283] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 141 eff = 0.998966
[15:10:27.283] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 131 eff = 0.999255
[15:10:27.283] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 165 eff = 0.999231
[15:10:27.283] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 140 eff = 0.999038
[15:10:27.284] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 164 eff = 0.999303
[15:10:27.284] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 154 eff = 0.999087
[15:10:27.284] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 174 eff = 0.999447
[15:10:27.284] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 174 eff = 0.999663
[15:10:27.285] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 145 eff = 0.999904
[15:10:27.285] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 172 eff = 0.999495
[15:10:34.074] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:10:34.074] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:10:34.074] INFO: ----------------------------------------------------------------------
[15:10:34.074] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:10:34.074] INFO: ----------------------------------------------------------------------
[15:10:34.074] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:10:34.074] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:10:34.074] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:10:34.075] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:10:34.075] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:10:34.075] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:10:34.075] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:10:34.075] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:10:34.075] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:10:34.075] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:10:34.075] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:10:34.075] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:10:34.092] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:34.760] INFO: Expecting 208000 events.
[15:10:47.855] INFO: 208000 events read in total (12569ms).
[15:10:47.866] INFO: Test took 13774ms.
[15:10:48.178] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:48.178] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1805862
[15:10:48.178] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:10:48.178] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:48.531] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[15:10:48.531] INFO: number of red-efficiency pixels: 78 61 96 188 207 208 201 204 180 203 197 209 176 122 60 58
[15:10:48.531] INFO: number of X-ray hits detected: 66624 50824 85802 126984 138059 139246 137611 126267 141732 142284 138757 131447 127414 80754 48186 57312
[15:10:48.531] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:10:48.531] INFO: number of Vcal hits detected: 207921 207938 207894 207811 207784 207781 207789 207788 207815 207789 207797 207783 207818 207873 207940 207893
[15:10:48.531] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[15:10:48.531] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[15:10:48.531] INFO: X-ray hit rate [MHz/cm2]: 19.5 14.9 25.1 37.2 40.5 40.8 40.3 37.0 41.5 41.7 40.7 38.5 37.3 23.7 14.1 16.8
[15:10:48.531] INFO: PixTestHighRate::doXPixelAlive() done
[15:10:48.577] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:10:48.577] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:10:48.577] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:10:48.577] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:10:48.577] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:10:48.577] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:10:48.577] INFO: PixTest:: pg_setup set to default.
[15:10:55.234] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:10:55.234] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[15:10:57.858] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:10:57.859] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 377.8mA
[15:10:57.859] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 475.9mA
[15:10:57.860] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[15:10:57.860] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:10:57.860] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:10:57.860] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:10:57.860] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:10:57.860] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:10:57.860] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:10:57.860] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:10:57.860] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:10:57.860] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:10:57.860] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:10:57.949] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:10:57.949] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:10:57.958] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:10:57.958] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:10:57.958] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:10:57.958] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:10:57.958] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:10:57.958] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:10:57.961] QUIET: Connection to board 58 closed.
[15:10:59.162] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[15:05:27.291] INFO: *** Welcome to pxar ***
[15:05:27.291] INFO: *** Today: 2016/06/20
[15:05:27.316] INFO: *** Version: v1.9.0-814-g7497
[15:05:27.316] INFO: readRocDacs: data/mn302/dacParameters35_C0.dat .. data/mn302/dacParameters35_C15.dat
[15:05:27.317] INFO: readTbmDacs: data/mn302/tbmParameters_C0a.dat .. data/mn302/tbmParameters_C0b.dat
[15:05:27.317] INFO: readMaskFile: data/mn302/defaultMaskFile.dat
[15:05:27.317] INFO: readTrimFile: data/mn302/trimParameters35_C0.dat .. data/mn302/trimParameters35_C15.dat
[15:05:27.468] INFO: clk: 4
[15:05:27.468] INFO: ctr: 4
[15:05:27.468] INFO: sda: 19
[15:05:27.468] INFO: tin: 9
[15:05:27.468] INFO: level: 15
[15:05:27.468] INFO: triggerdelay: 0
[15:05:27.468] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:05:27.468] INFO: Log level: DEBUG
[15:05:27.480] QUIET: Connection to board DTB_WRPRHI opened.
[15:05:27.483] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:05:27.486] INFO: RPC call hashes of host and DTB match: 398089610
[15:05:29.088] INFO: DUT info:
[15:05:29.088] INFO: The DUT currently contains the following objects:
[15:05:29.088] INFO: 2 TBM Cores tbm08c (2 ON)
[15:05:29.088] INFO: TBM Core alpha (0): 7 registers set
[15:05:29.088] INFO: TBM Core beta (1): 7 registers set
[15:05:29.088] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:05:29.088] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.088] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.089] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.089] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.089] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.089] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.089] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.089] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.089] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.089] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.089] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.089] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.089] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.089] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.089] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.089] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:05:29.089] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:05:29.089] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:05:29.089] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:05:29.089] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:05:29.089] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:05:29.089] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:05:29.089] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:05:29.089] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:05:29.089] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:05:29.089] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:05:29.089] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:05:29.089] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:05:29.089] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:05:29.090] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:05:29.091] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:05:29.092] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:05:29.094] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35147776
[15:05:29.095] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x122f110
[15:05:29.095] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x11bd360
[15:05:29.095] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fc2a5d94010
[15:05:29.095] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fc2abfff510
[15:05:29.095] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35155968 fPxarMemory = 0x7fc2a5d94010
[15:05:29.096] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 376.2mA
[15:05:29.097] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 475.1mA
[15:05:29.097] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:05:29.593] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1805d60
[15:05:29.656] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:05:29.657] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:05:29.657] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:05:29.662] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:05:29.662] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:05:29.662] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:05:29.671] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:05:29.671] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:05:29.693] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:05:29.693] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:05:29.703] INFO: PixTestCmd::init()
[15:05:29.707] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:05:29.707] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:05:29.707] INFO: readGainPedestalParameters data/mn302/phCalibrationFitErr35_C0.dat .. data/mn302/phCalibrationFitErr35_C15.dat
[15:05:29.950] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:05:29.950] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:05:29.962] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:05:29.968] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:05:29.968] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:05:29.968] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:05:29.987] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:05:29.987] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:05:29.987] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:05:29.987] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:05:29.987] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:05:29.993] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:05:29.993] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:05:30.008] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:05:30.008] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:05:30.009] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:05:30.009] INFO: readReadbackCal: data/mn302/readbackCal_C0.dat .. data/mn302/readbackCal_C15.dat
[15:05:30.019] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:05:30.026] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:05:30.026] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:05:30.026] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:05:30.026] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:05:30.037] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:05:30.037] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:05:52.117] INFO: ######################################################################
[15:05:52.117] INFO: PixTestAlive::doTest()
[15:05:52.117] INFO: ######################################################################
[15:05:52.121] INFO: ----------------------------------------------------------------------
[15:05:52.121] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:05:52.121] INFO: ----------------------------------------------------------------------
[15:05:52.123] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:05:52.501] INFO: Expecting 41600 events.
[15:05:56.895] INFO: 41600 events read in total (3676ms).
[15:05:57.042] INFO: Test took 4919ms.
[15:05:57.056] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:57.056] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:05:57.056] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:05:57.329] INFO: PixTestAlive::aliveTest() done
[15:05:57.329] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[15:05:57.329] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 29 28 33 62 74 56 61 37 52 57 58 56 63 42 17 25
[15:05:57.353] INFO: ----------------------------------------------------------------------
[15:05:57.353] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:05:57.353] INFO: ----------------------------------------------------------------------
[15:05:57.355] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:05:57.733] INFO: Expecting 41600 events.
[15:06:00.864] INFO: 41600 events read in total (2415ms).
[15:06:00.865] INFO: Test took 3510ms.
[15:06:00.865] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:00.865] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:06:00.865] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:06:00.865] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:06:01.287] INFO: PixTestAlive::maskTest() done
[15:06:01.287] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:06:01.310] INFO: ----------------------------------------------------------------------
[15:06:01.310] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:06:01.310] INFO: ----------------------------------------------------------------------
[15:06:01.313] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:06:01.689] INFO: Expecting 41600 events.
[15:06:06.087] INFO: 41600 events read in total (3683ms).
[15:06:06.088] INFO: Test took 4775ms.
[15:06:06.100] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:06.100] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:06:06.100] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:06:06.469] INFO: PixTestAlive::addressDecodingTest() done
[15:06:06.469] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:06:06.470] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:06:10.522] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:06:10.522] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:06:10.522] INFO: ----------------------------------------------------------------------
[15:06:10.522] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:06:10.522] INFO: ----------------------------------------------------------------------
[15:06:10.661] INFO: Expecting 768 events.
[15:06:11.795] INFO: 768 events read in total (419ms).
[15:06:11.795] INFO: Test took 1267ms.
[15:06:11.801] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:06:12.598] INFO: Expecting 41600 events.
[15:06:16.208] INFO: 41600 events read in total (3083ms).
[15:06:16.213] INFO: Test took 4412ms.
[15:06:16.327] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:16.327] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 654925
[15:06:16.327] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:06:16.327] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:06:16.377] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:06:17.042] INFO: Expecting 41600 events.
[15:06:20.735] INFO: 41600 events read in total (3166ms).
[15:06:20.739] INFO: Test took 4362ms.
[15:06:20.852] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:20.852] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 660575
[15:06:20.852] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:06:20.853] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:06:20.904] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:06:21.570] INFO: Expecting 41600 events.
[15:06:25.303] INFO: 41600 events read in total (3206ms).
[15:06:25.307] INFO: Test took 4403ms.
[15:06:25.419] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:25.419] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 662561
[15:06:25.419] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:06:25.419] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:06:25.471] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:06:26.136] INFO: Expecting 41600 events.
[15:06:29.873] INFO: 41600 events read in total (3211ms).
[15:06:29.877] INFO: Test took 4406ms.
[15:06:29.986] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:29.986] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 664546
[15:06:29.986] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:06:29.987] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:06:30.040] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:06:30.705] INFO: Expecting 41600 events.
[15:06:34.441] INFO: 41600 events read in total (3209ms).
[15:06:34.446] INFO: Test took 4406ms.
[15:06:34.558] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:34.558] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 662180
[15:06:34.558] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:06:34.558] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:06:34.610] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:06:35.275] INFO: Expecting 41600 events.
[15:06:39.023] INFO: 41600 events read in total (3221ms).
[15:06:39.028] INFO: Test took 4418ms.
[15:06:39.140] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:39.140] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 665208
[15:06:39.140] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:06:39.141] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:06:39.192] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:06:39.857] INFO: Expecting 41600 events.
[15:06:43.606] INFO: 41600 events read in total (3222ms).
[15:06:43.610] INFO: Test took 4418ms.
[15:06:43.723] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:43.723] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 665248
[15:06:43.723] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:06:43.724] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:06:43.775] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:06:44.440] INFO: Expecting 41600 events.
[15:06:48.195] INFO: 41600 events read in total (3228ms).
[15:06:48.199] INFO: Test took 4424ms.
[15:06:48.312] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:48.312] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 663265
[15:06:48.312] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:06:48.312] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:06:48.363] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:06:49.029] INFO: Expecting 41600 events.
[15:06:52.786] INFO: 41600 events read in total (3230ms).
[15:06:52.790] INFO: Test took 4427ms.
[15:06:52.903] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:52.903] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 663550
[15:06:52.903] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:06:52.903] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:06:52.954] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:06:53.619] INFO: Expecting 41600 events.
[15:06:57.377] INFO: 41600 events read in total (3231ms).
[15:06:57.382] INFO: Test took 4428ms.
[15:06:57.492] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:57.492] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 663251
[15:06:57.492] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:06:57.492] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:06:57.544] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:06:58.209] INFO: Expecting 41600 events.
[15:07:01.965] INFO: 41600 events read in total (3229ms).
[15:07:01.970] INFO: Test took 4426ms.
[15:07:02.081] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:02.081] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 663887
[15:07:02.081] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:07:02.081] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:02.133] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:02.799] INFO: Expecting 41600 events.
[15:07:06.558] INFO: 41600 events read in total (3232ms).
[15:07:06.563] INFO: Test took 4430ms.
[15:07:06.676] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:06.676] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 664306
[15:07:06.676] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:07:06.676] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:06.728] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:07.393] INFO: Expecting 41600 events.
[15:07:11.125] INFO: 41600 events read in total (3205ms).
[15:07:11.129] INFO: Test took 4401ms.
[15:07:11.242] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:11.242] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 662133
[15:07:11.242] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:07:11.242] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:11.294] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:11.959] INFO: Expecting 41600 events.
[15:07:15.714] INFO: 41600 events read in total (3228ms).
[15:07:15.718] INFO: Test took 4424ms.
[15:07:15.830] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:15.830] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 663487
[15:07:15.830] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:07:15.830] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:15.882] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:16.547] INFO: Expecting 41600 events.
[15:07:20.305] INFO: 41600 events read in total (3232ms).
[15:07:20.309] INFO: Test took 4427ms.
[15:07:20.422] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:20.422] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 664791
[15:07:20.422] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:07:20.423] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:20.474] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:21.139] INFO: Expecting 41600 events.
[15:07:24.892] INFO: 41600 events read in total (3226ms).
[15:07:24.897] INFO: Test took 4423ms.
[15:07:25.008] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:25.008] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 662336
[15:07:25.008] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:07:25.009] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:25.060] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:25.725] INFO: Expecting 41600 events.
[15:07:29.471] INFO: 41600 events read in total (3219ms).
[15:07:29.475] INFO: Test took 4415ms.
[15:07:29.586] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:29.586] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 663108
[15:07:29.586] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:07:29.586] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:29.639] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:30.304] INFO: Expecting 41600 events.
[15:07:34.054] INFO: 41600 events read in total (3223ms).
[15:07:34.058] INFO: Test took 4419ms.
[15:07:34.170] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:34.170] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 661153
[15:07:34.170] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:07:34.170] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:34.222] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:34.887] INFO: Expecting 41600 events.
[15:07:38.609] INFO: 41600 events read in total (3196ms).
[15:07:38.614] INFO: Test took 4392ms.
[15:07:38.722] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:38.722] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 661614
[15:07:38.722] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:07:38.723] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:38.775] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:39.440] INFO: Expecting 41600 events.
[15:07:42.994] INFO: 41600 events read in total (3027ms).
[15:07:42.998] INFO: Test took 4223ms.
[15:07:43.106] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:43.106] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 656384
[15:07:43.106] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:07:43.107] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:43.379] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 164 eff = 0.999279
[15:07:43.380] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 155 eff = 0.999447
[15:07:43.380] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 168 eff = 0.998678
[15:07:43.380] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 161 eff = 0.998173
[15:07:43.380] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 156 eff = 0.997981
[15:07:43.381] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 152 eff = 0.997933
[15:07:43.381] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 140 eff = 0.997861
[15:07:43.381] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 148 eff = 0.998317
[15:07:43.381] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 164 eff = 0.997716
[15:07:43.382] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 129 eff = 0.99726
[15:07:43.382] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 161 eff = 0.997837
[15:07:43.382] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 169 eff = 0.997788
[15:07:43.382] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 170 eff = 0.998005
[15:07:43.383] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 177 eff = 0.998798
[15:07:43.383] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 148 eff = 0.999591
[15:07:43.383] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 173 eff = 0.999231
[15:08:06.130] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:08:06.130] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:08:06.130] INFO: ----------------------------------------------------------------------
[15:08:06.130] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:08:06.130] INFO: ----------------------------------------------------------------------
[15:08:06.131] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:08:06.131] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:08:06.131] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:08:06.131] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:08:06.131] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:08:06.131] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:08:06.131] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:08:06.131] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:08:06.131] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:08:06.131] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:08:06.131] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:08:06.131] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:08:06.149] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:08:06.815] INFO: Expecting 208000 events.
[15:08:21.813] INFO: 208000 events read in total (14471ms).
[15:08:21.829] INFO: Test took 15680ms.
[15:08:22.347] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:22.347] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3022873
[15:08:22.347] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:08:22.347] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:08:22.775] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[15:08:22.775] INFO: number of red-efficiency pixels: 170 139 303 415 425 486 406 431 480 561 431 432 408 227 140 142
[15:08:22.775] INFO: number of X-ray hits detected: 113405 87313 145015 217145 235271 234699 234211 213440 239886 241677 234862 223908 216962 137937 82394 98189
[15:08:22.775] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:08:22.775] INFO: number of Vcal hits detected: 207820 207854 207672 207562 207539 207474 207556 207532 207481 207381 207537 207525 207561 207764 207858 207805
[15:08:22.775] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.9 99.9 99.9
[15:08:22.775] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.9 99.9 99.9
[15:08:22.775] INFO: X-ray hit rate [MHz/cm2]: 33.2 25.6 42.5 63.6 69.0 68.8 68.6 62.6 70.3 70.8 68.8 65.6 63.6 40.4 24.2 28.8
[15:08:22.775] INFO: PixTestHighRate::doXPixelAlive() done
[15:08:22.822] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:08:22.822] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:08:22.822] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:08:22.822] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:08:22.822] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:08:22.822] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:08:22.822] INFO: PixTest:: pg_setup set to default.
[15:08:27.130] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:08:27.130] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[15:08:28.530] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:08:28.531] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 377mA
[15:08:28.531] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 475.9mA
[15:08:28.531] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:08:28.531] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:08:28.531] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:08:28.532] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:08:28.532] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:08:28.532] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:08:28.532] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:08:28.532] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:08:28.532] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:08:28.532] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:08:28.532] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:08:28.622] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:08:28.622] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:08:28.631] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:08:28.632] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:08:28.632] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:08:28.632] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:08:28.632] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:08:28.632] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:08:28.634] QUIET: Connection to board 58 closed.
[15:08:28.714] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[15:02:36.144] INFO: *** Welcome to pxar ***
[15:02:36.144] INFO: *** Today: 2016/06/20
[15:02:36.167] INFO: *** Version: v1.9.0-814-g7497
[15:02:36.167] INFO: readRocDacs: data/mn302/dacParameters35_C0.dat .. data/mn302/dacParameters35_C15.dat
[15:02:36.168] INFO: readTbmDacs: data/mn302/tbmParameters_C0a.dat .. data/mn302/tbmParameters_C0b.dat
[15:02:36.168] INFO: readMaskFile: data/mn302/defaultMaskFile.dat
[15:02:36.169] INFO: readTrimFile: data/mn302/trimParameters35_C0.dat .. data/mn302/trimParameters35_C15.dat
[15:02:36.318] INFO: clk: 4
[15:02:36.318] INFO: ctr: 4
[15:02:36.318] INFO: sda: 19
[15:02:36.318] INFO: tin: 9
[15:02:36.318] INFO: level: 15
[15:02:36.318] INFO: triggerdelay: 0
[15:02:36.318] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:02:36.318] INFO: Log level: DEBUG
[15:02:36.329] QUIET: Connection to board DTB_WRPRHI opened.
[15:02:36.333] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:02:36.336] INFO: RPC call hashes of host and DTB match: 398089610
[15:02:37.935] INFO: DUT info:
[15:02:37.936] INFO: The DUT currently contains the following objects:
[15:02:37.936] INFO: 2 TBM Cores tbm08c (2 ON)
[15:02:37.936] INFO: TBM Core alpha (0): 7 registers set
[15:02:37.936] INFO: TBM Core beta (1): 7 registers set
[15:02:37.936] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:02:37.936] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:37.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:37.937] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:02:37.938] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:02:37.939] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:02:37.940] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:02:37.940] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:02:37.940] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:02:37.940] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:02:37.940] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:02:37.942] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32759808
[15:02:37.942] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x159b110
[15:02:37.942] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1529360
[15:02:37.942] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f9ed5d94010
[15:02:37.942] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f9edbfff510
[15:02:37.942] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32768000 fPxarMemory = 0x7f9ed5d94010
[15:02:37.943] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 375.4mA
[15:02:37.944] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 475.9mA
[15:02:37.944] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:02:38.439] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1b71db0
[15:02:38.500] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:02:38.500] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:02:38.500] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:02:38.505] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:02:38.505] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:02:38.505] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:02:38.513] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:02:38.514] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:02:38.526] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:02:38.526] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:02:38.539] INFO: PixTestCmd::init()
[15:02:38.546] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:02:38.546] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:02:38.546] INFO: readGainPedestalParameters data/mn302/phCalibrationFitErr35_C0.dat .. data/mn302/phCalibrationFitErr35_C15.dat
[15:02:38.790] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:02:38.791] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:02:38.802] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:02:38.809] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:02:38.809] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:02:38.809] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:02:38.828] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:02:38.828] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:02:38.828] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:02:38.828] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:02:38.828] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:02:38.834] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:02:38.834] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:02:38.847] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:02:38.847] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:02:38.847] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:02:38.847] INFO: readReadbackCal: data/mn302/readbackCal_C0.dat .. data/mn302/readbackCal_C15.dat
[15:02:38.858] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:02:38.866] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:02:38.866] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:02:38.866] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:02:38.866] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:02:38.876] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:02:38.877] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:02:42.461] INFO: ######################################################################
[15:02:42.461] INFO: PixTestAlive::doTest()
[15:02:42.461] INFO: ######################################################################
[15:02:42.464] INFO: ----------------------------------------------------------------------
[15:02:42.464] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:02:42.464] INFO: ----------------------------------------------------------------------
[15:02:42.467] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:42.846] INFO: Expecting 41600 events.
[15:02:47.242] INFO: 41600 events read in total (3678ms).
[15:02:47.389] INFO: Test took 4922ms.
[15:02:47.399] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:47.399] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:02:47.399] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:02:47.673] INFO: PixTestAlive::aliveTest() done
[15:02:47.673] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[15:02:47.673] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 39 27 43 72 73 90 89 63 68 71 63 71 73 52 34 40
[15:02:47.699] INFO: ----------------------------------------------------------------------
[15:02:47.699] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:02:47.699] INFO: ----------------------------------------------------------------------
[15:02:47.701] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:48.077] INFO: Expecting 41600 events.
[15:02:51.207] INFO: 41600 events read in total (2415ms).
[15:02:51.207] INFO: Test took 3506ms.
[15:02:51.207] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:51.207] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:02:51.207] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:02:51.208] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:02:51.630] INFO: PixTestAlive::maskTest() done
[15:02:51.630] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:02:51.650] INFO: ----------------------------------------------------------------------
[15:02:51.650] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:02:51.650] INFO: ----------------------------------------------------------------------
[15:02:51.652] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:52.029] INFO: Expecting 41600 events.
[15:02:56.411] INFO: 41600 events read in total (3667ms).
[15:02:56.412] INFO: Test took 4760ms.
[15:02:56.423] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:56.423] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:02:56.423] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:02:56.794] INFO: PixTestAlive::addressDecodingTest() done
[15:02:56.795] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:02:56.795] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:03:03.834] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:03:03.834] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:03:03.834] INFO: ----------------------------------------------------------------------
[15:03:03.834] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:03:03.834] INFO: ----------------------------------------------------------------------
[15:03:03.973] INFO: Expecting 768 events.
[15:03:05.107] INFO: 768 events read in total (418ms).
[15:03:05.107] INFO: Test took 1267ms.
[15:03:05.113] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:05.911] INFO: Expecting 41600 events.
[15:03:09.681] INFO: 41600 events read in total (3245ms).
[15:03:09.687] INFO: Test took 4574ms.
[15:03:09.831] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:09.831] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 821131
[15:03:09.831] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:03:09.832] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:09.892] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:10.558] INFO: Expecting 41600 events.
[15:03:14.410] INFO: 41600 events read in total (3326ms).
[15:03:14.415] INFO: Test took 4523ms.
[15:03:14.548] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:14.549] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 826388
[15:03:14.549] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:03:14.549] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:14.613] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:15.278] INFO: Expecting 41600 events.
[15:03:19.182] INFO: 41600 events read in total (3377ms).
[15:03:19.187] INFO: Test took 4574ms.
[15:03:19.323] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:19.323] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 830342
[15:03:19.323] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:03:19.324] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:19.387] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:20.053] INFO: Expecting 41600 events.
[15:03:23.966] INFO: 41600 events read in total (3386ms).
[15:03:23.972] INFO: Test took 4585ms.
[15:03:24.105] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:24.106] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 830014
[15:03:24.106] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:03:24.106] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:24.170] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:24.836] INFO: Expecting 41600 events.
[15:03:28.746] INFO: 41600 events read in total (3383ms).
[15:03:28.752] INFO: Test took 4582ms.
[15:03:28.887] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:28.887] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 831347
[15:03:28.887] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:03:28.887] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:28.951] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:29.616] INFO: Expecting 41600 events.
[15:03:33.532] INFO: 41600 events read in total (3389ms).
[15:03:33.537] INFO: Test took 4586ms.
[15:03:33.673] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:33.673] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 830857
[15:03:33.673] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:03:33.674] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:33.738] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:34.403] INFO: Expecting 41600 events.
[15:03:38.324] INFO: 41600 events read in total (3394ms).
[15:03:38.329] INFO: Test took 4591ms.
[15:03:38.465] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:38.465] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 833111
[15:03:38.465] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:03:38.466] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:38.530] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:39.195] INFO: Expecting 41600 events.
[15:03:43.141] INFO: 41600 events read in total (3419ms).
[15:03:43.146] INFO: Test took 4617ms.
[15:03:43.280] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:43.280] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 830946
[15:03:43.280] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:03:43.281] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:43.345] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:44.010] INFO: Expecting 41600 events.
[15:03:47.963] INFO: 41600 events read in total (3426ms).
[15:03:47.968] INFO: Test took 4623ms.
[15:03:48.103] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:48.103] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 831955
[15:03:48.103] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:03:48.103] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:48.167] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:48.832] INFO: Expecting 41600 events.
[15:03:52.773] INFO: 41600 events read in total (3414ms).
[15:03:52.778] INFO: Test took 4611ms.
[15:03:52.913] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:52.913] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 830417
[15:03:52.913] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:03:52.914] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:52.977] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:53.642] INFO: Expecting 41600 events.
[15:03:57.582] INFO: 41600 events read in total (3414ms).
[15:03:57.587] INFO: Test took 4610ms.
[15:03:57.723] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:57.723] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 832006
[15:03:57.724] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:03:57.724] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:57.788] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:58.453] INFO: Expecting 41600 events.
[15:04:02.398] INFO: 41600 events read in total (3419ms).
[15:04:02.403] INFO: Test took 4615ms.
[15:04:02.540] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:02.540] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 830871
[15:04:02.540] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:04:02.540] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:04:02.605] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:04:03.270] INFO: Expecting 41600 events.
[15:04:07.208] INFO: 41600 events read in total (3411ms).
[15:04:07.213] INFO: Test took 4608ms.
[15:04:07.350] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:07.351] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 831321
[15:04:07.351] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:04:07.351] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:04:07.415] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:04:08.080] INFO: Expecting 41600 events.
[15:04:12.000] INFO: 41600 events read in total (3394ms).
[15:04:12.006] INFO: Test took 4591ms.
[15:04:12.140] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:12.140] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 830187
[15:04:12.140] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:04:12.141] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:04:12.204] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:04:12.869] INFO: Expecting 41600 events.
[15:04:16.798] INFO: 41600 events read in total (3402ms).
[15:04:16.803] INFO: Test took 4599ms.
[15:04:16.936] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:16.936] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 830509
[15:04:16.936] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:04:16.936] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:04:17.000] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:04:17.665] INFO: Expecting 41600 events.
[15:04:21.567] INFO: 41600 events read in total (3375ms).
[15:04:21.572] INFO: Test took 4572ms.
[15:04:21.707] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:21.707] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 831407
[15:04:21.707] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:04:21.707] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:04:21.771] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:04:22.436] INFO: Expecting 41600 events.
[15:04:26.377] INFO: 41600 events read in total (3414ms).
[15:04:26.382] INFO: Test took 4612ms.
[15:04:26.521] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:26.521] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 831829
[15:04:26.521] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:04:26.522] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:04:26.586] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:04:27.251] INFO: Expecting 41600 events.
[15:04:31.184] INFO: 41600 events read in total (3407ms).
[15:04:31.189] INFO: Test took 4603ms.
[15:04:31.324] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:31.324] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 829983
[15:04:31.324] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:04:31.324] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:04:31.388] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:04:32.053] INFO: Expecting 41600 events.
[15:04:35.986] INFO: 41600 events read in total (3406ms).
[15:04:35.991] INFO: Test took 4603ms.
[15:04:36.125] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:36.125] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 830224
[15:04:36.125] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:04:36.125] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:04:36.190] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:04:36.856] INFO: Expecting 41600 events.
[15:04:40.603] INFO: 41600 events read in total (3220ms).
[15:04:40.608] INFO: Test took 4418ms.
[15:04:40.744] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:40.744] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 823890
[15:04:40.744] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:04:40.744] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:04:41.028] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 179 eff = 0.998942
[15:04:41.028] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 155 eff = 0.999231
[15:04:41.028] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 178 eff = 0.997981
[15:04:41.029] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 166 eff = 0.996755
[15:04:41.029] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 156 eff = 0.996755
[15:04:41.029] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 151 eff = 0.996034
[15:04:41.029] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 139 eff = 0.995793
[15:04:41.029] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 153 eff = 0.996971
[15:04:41.029] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 164 eff = 0.996274
[15:04:41.029] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 143 eff = 0.996611
[15:04:41.029] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 164 eff = 0.99625
[15:04:41.030] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 169 eff = 0.996683
[15:04:41.030] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 173 eff = 0.996803
[15:04:41.030] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 183 eff = 0.998341
[15:04:41.030] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 164 eff = 0.999351
[15:04:41.030] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 173 eff = 0.99899
[15:04:59.266] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:04:59.266] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:04:59.266] INFO: ----------------------------------------------------------------------
[15:04:59.266] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:04:59.266] INFO: ----------------------------------------------------------------------
[15:04:59.266] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:04:59.266] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:04:59.266] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:04:59.266] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:04:59.266] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:04:59.267] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:04:59.267] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:04:59.267] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:04:59.267] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:04:59.267] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:04:59.267] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:04:59.267] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:04:59.284] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:04:59.951] INFO: Expecting 208000 events.
[15:05:16.302] INFO: 208000 events read in total (15824ms).
[15:05:16.321] INFO: Test took 17037ms.
[15:05:16.981] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:16.981] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3850625
[15:05:16.981] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:05:16.981] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:05:17.469] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[15:05:17.469] INFO: number of red-efficiency pixels: 233 183 365 587 704 708 718 597 704 758 706 698 645 351 165 180
[15:05:17.469] INFO: number of X-ray hits detected: 144658 112377 186560 275922 299939 300784 299194 274023 307361 309551 300508 286651 278750 177353 104742 125693
[15:05:17.469] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:05:17.469] INFO: number of Vcal hits detected: 207758 207804 207579 207352 207207 207181 207170 207344 207216 207148 207194 207215 207269 207610 207831 207767
[15:05:17.469] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.7 99.6 99.7 99.7 99.6 99.6 99.7 99.7 99.8 99.9 99.9
[15:05:17.469] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.6 99.6 99.7 99.6 99.6 99.6 99.6 99.6 99.8 99.9 99.9
[15:05:17.469] INFO: X-ray hit rate [MHz/cm2]: 42.4 32.9 54.7 80.9 87.9 88.2 87.7 80.3 90.1 90.7 88.1 84.0 81.7 52.0 30.7 36.8
[15:05:17.469] INFO: PixTestHighRate::doXPixelAlive() done
[15:05:17.515] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:05:17.515] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:05:17.515] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:05:17.515] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:05:17.515] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:05:17.515] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:05:17.515] INFO: PixTest:: pg_setup set to default.
[15:05:20.370] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:05:20.370] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[15:05:21.442] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:05:21.443] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 377.8mA
[15:05:21.443] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 475.9mA
[15:05:21.443] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[15:05:21.443] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:05:21.444] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:05:21.444] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:05:21.444] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:05:21.444] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:05:21.444] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:05:21.444] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:05:21.444] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:05:21.444] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:05:21.444] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:05:21.535] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:05:21.535] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:05:21.544] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:05:21.544] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:05:21.544] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:05:21.545] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:05:21.545] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:05:21.545] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:05:21.547] QUIET: Connection to board 58 closed.
[15:05:21.627] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master