Test Date: 1973-11-29 16:33
Analysis date: 2016-06-02 17:16
Logfile
hrData_40.log
[09:01:47.325] INFO: *** Welcome to pxar ***
[09:01:47.325] INFO: *** Today: 2016/06/02
[09:01:47.347] INFO: *** Version: v1.9.0-805-geb36
[09:01:47.347] INFO: readRocDacs: data/mydnm/dacParameters35_C0.dat .. data/mydnm/dacParameters35_C15.dat
[09:01:47.348] INFO: readTbmDacs: data/mydnm/tbmParameters_C0a.dat .. data/mydnm/tbmParameters_C0b.dat
[09:01:47.348] INFO: readMaskFile: data/mydnm/defaultMaskFile.dat
[09:01:47.348] INFO: readTrimFile: data/mydnm/trimParameters35_C0.dat .. data/mydnm/trimParameters35_C15.dat
[09:01:47.501] INFO: clk: 4
[09:01:47.501] INFO: ctr: 4
[09:01:47.501] INFO: sda: 19
[09:01:47.501] INFO: tin: 9
[09:01:47.501] INFO: level: 15
[09:01:47.501] INFO: triggerdelay: 0
[09:01:47.501] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[09:01:47.501] INFO: Log level: DEBUG
[09:01:47.512] QUIET: Connection to board DTB_WRPRHI opened.
[09:01:47.515] 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:
------------------------------------------------------
[09:01:47.518] INFO: RPC call hashes of host and DTB match: 398089610
[09:01:49.118] INFO: DUT info:
[09:01:49.118] INFO: The DUT currently contains the following objects:
[09:01:49.118] INFO: 2 TBM Cores tbm08c (2 ON)
[09:01:49.118] INFO: TBM Core alpha (0): 7 registers set
[09:01:49.118] INFO: TBM Core beta (1): 7 registers set
[09:01:49.118] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:01:49.118] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.118] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.118] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.118] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.118] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.118] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.118] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.119] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.119] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.119] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.119] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.119] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.119] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.119] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.119] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.119] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[09:01:49.119] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[09:01:49.120] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:01:49.121] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:01:49.123] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32751616
[09:01:49.123] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x17e6bd0
[09:01:49.124] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1588e60
[09:01:49.124] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f6ab7d83010
[09:01:49.124] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f6abdfee510
[09:01:49.124] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32759808 fPxarMemory = 0x7f6ab7d83010
[09:01:49.125] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[09:01:49.126] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 470.3mA
[09:01:49.126] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[09:01:49.615] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1bd1940
[09:01:49.679] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[09:01:49.680] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[09:01:49.680] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[09:01:49.686] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[09:01:49.686] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[09:01:49.686] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[09:01:49.697] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[09:01:49.697] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[09:01:49.719] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[09:01:49.719] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[09:01:49.733] INFO: PixTestCmd::init()
[09:01:49.749] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[09:01:49.749] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[09:01:49.749] INFO: readGainPedestalParameters data/mydnm/phCalibrationFitErr35_C0.dat .. data/mydnm/phCalibrationFitErr35_C15.dat
[09:01:50.017] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[09:01:50.017] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[09:01:50.032] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[09:01:50.037] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[09:01:50.037] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[09:01:50.038] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[09:01:50.059] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[09:01:50.059] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[09:01:50.059] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[09:01:50.059] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[09:01:50.059] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[09:01:50.067] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[09:01:50.067] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[09:01:50.081] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[09:01:50.081] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[09:01:50.081] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[09:01:50.081] INFO: readReadbackCal: data/mydnm/readbackCal_C0.dat .. data/mydnm/readbackCal_C15.dat
[09:01:50.091] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[09:01:50.099] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[09:01:50.099] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[09:01:50.099] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[09:01:50.099] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[09:01:50.136] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[09:01:50.136] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[09:07:18.089] INFO: ######################################################################
[09:07:18.089] INFO: PixTestXray::doTest()
[09:07:18.089] INFO: ######################################################################
[09:07:18.089] INFO: ----------------------------------------------------------------------
[09:07:18.089] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:07:18.089] INFO: ----------------------------------------------------------------------
[09:07:19.061] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:07:30.176] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[09:07:30.180] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[09:07:54.773] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1095189 events.
[09:08:02.410] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1095189, pixels seen in all events: 7110232
[09:08:02.590] INFO: Resuming triggers.
[09:08:13.724] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[09:08:13.751] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[09:08:38.214] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1096814 events.
[09:08:46.127] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1096814, pixels seen in all events: 7121388
[09:08:46.229] INFO: Resuming triggers.
[09:08:57.341] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[09:08:57.368] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[09:09:21.642] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1094685 events.
[09:09:29.403] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1094685, pixels seen in all events: 7115086
[09:09:29.483] INFO: Resuming triggers.
[09:09:40.615] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[09:09:40.642] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[09:10:05.043] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1096649 events.
[09:10:12.827] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1096649, pixels seen in all events: 7134802
[09:10:12.908] INFO: Resuming triggers.
[09:10:24.044] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[09:10:24.070] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[09:10:48.344] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1097011 events.
[09:10:56.017] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1097011, pixels seen in all events: 7131588
[09:10:56.202] INFO: Resuming triggers.
[09:11:07.341] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[09:11:07.372] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[09:11:31.781] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1097331 events.
[09:11:39.541] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1097331, pixels seen in all events: 7139778
[09:11:39.624] INFO: Resuming triggers.
[09:11:50.774] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[09:11:50.803] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[09:12:15.169] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1098424 events.
[09:12:22.942] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1098424, pixels seen in all events: 7145972
[09:12:23.021] INFO: Resuming triggers.
[09:12:34.132] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[09:12:34.158] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[09:12:58.707] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1094510 events.
[09:13:06.055] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1094510, pixels seen in all events: 7117557
[09:13:06.136] INFO: Resuming triggers.
[09:13:17.269] INFO: data taking finished, elapsed time: 100 seconds.
[09:13:17.465] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[09:13:41.921] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1096733 events.
[09:13:49.418] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1096733, pixels seen in all events: 7126744
[09:13:49.498] INFO: PixTest:: pg_setup set to default.
[09:13:49.633] INFO: PixTestXray::doPhRun() done
[09:13:49.633] INFO: PixTestXray::doTest() done
[09:16:14.817] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[09:16:14.834] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hMap_Ag_C11_V0 -> hMap_Ag_mod
[09:16:17.681] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[09:16:17.681] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 386.7mA
[09:16:17.682] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 471.1mA
[09:16:17.682] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[09:16:17.682] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[09:16:17.682] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[09:16:17.683] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[09:16:17.683] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[09:16:17.683] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[09:16:17.683] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[09:16:17.683] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[09:16:17.683] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[09:16:17.683] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[09:16:17.683] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[09:16:17.683] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[09:16:17.683] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[09:16:17.683] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[09:16:17.684] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[09:16:17.684] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[09:16:17.684] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[09:16:17.684] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[09:16:17.684] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[09:16:17.986] QUIET: Connection to board 58 closed.
[09:16:18.066] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[08:50:25.888] INFO: *** Welcome to pxar ***
[08:50:25.888] INFO: *** Today: 2016/06/02
[08:50:25.910] INFO: *** Version: v1.9.0-805-geb36
[08:50:25.910] INFO: readRocDacs: data/mydnm/dacParameters35_C0.dat .. data/mydnm/dacParameters35_C15.dat
[08:50:25.911] INFO: readTbmDacs: data/mydnm/tbmParameters_C0a.dat .. data/mydnm/tbmParameters_C0b.dat
[08:50:25.911] INFO: readMaskFile: data/mydnm/defaultMaskFile.dat
[08:50:25.911] INFO: readTrimFile: data/mydnm/trimParameters35_C0.dat .. data/mydnm/trimParameters35_C15.dat
[08:50:26.059] INFO: clk: 4
[08:50:26.059] INFO: ctr: 4
[08:50:26.059] INFO: sda: 19
[08:50:26.059] INFO: tin: 9
[08:50:26.059] INFO: level: 15
[08:50:26.059] INFO: triggerdelay: 0
[08:50:26.060] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[08:50:26.060] INFO: Log level: DEBUG
[08:50:26.071] QUIET: Connection to board DTB_WRPRHI opened.
[08:50:26.074] 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:
------------------------------------------------------
[08:50:26.077] INFO: RPC call hashes of host and DTB match: 398089610
[08:50:27.676] INFO: DUT info:
[08:50:27.676] INFO: The DUT currently contains the following objects:
[08:50:27.676] INFO: 2 TBM Cores tbm08c (2 ON)
[08:50:27.676] INFO: TBM Core alpha (0): 7 registers set
[08:50:27.677] INFO: TBM Core beta (1): 7 registers set
[08:50:27.677] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:50:27.677] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[08:50:27.677] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[08:50:27.678] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:50:27.679] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:50:27.682] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34983936
[08:50:27.682] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1d24bd0
[08:50:27.682] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1ac6e60
[08:50:27.682] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fc0edd94010
[08:50:27.682] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fc0f3fff510
[08:50:27.682] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34992128 fPxarMemory = 0x7fc0edd94010
[08:50:27.683] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[08:50:27.684] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 470.3mA
[08:50:27.684] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[08:50:28.174] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x210f7f0
[08:50:28.234] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[08:50:28.235] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[08:50:28.235] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[08:50:28.240] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[08:50:28.240] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[08:50:28.240] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[08:50:28.252] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[08:50:28.252] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[08:50:28.268] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[08:50:28.268] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[08:50:28.284] INFO: PixTestCmd::init()
[08:50:28.290] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[08:50:28.290] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[08:50:28.290] INFO: readGainPedestalParameters data/mydnm/phCalibrationFitErr35_C0.dat .. data/mydnm/phCalibrationFitErr35_C15.dat
[08:50:28.534] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[08:50:28.534] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[08:50:28.549] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[08:50:28.555] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[08:50:28.555] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[08:50:28.555] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[08:50:28.578] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[08:50:28.578] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[08:50:28.578] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[08:50:28.578] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[08:50:28.578] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[08:50:28.584] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[08:50:28.584] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[08:50:28.599] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[08:50:28.599] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[08:50:28.599] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[08:50:28.599] INFO: readReadbackCal: data/mydnm/readbackCal_C0.dat .. data/mydnm/readbackCal_C15.dat
[08:50:28.614] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[08:50:28.623] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[08:50:28.623] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[08:50:28.623] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[08:50:28.623] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[08:50:28.645] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[08:50:28.645] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[08:50:32.824] INFO: ######################################################################
[08:50:32.824] INFO: PixTestXray::doTest()
[08:50:32.824] INFO: ######################################################################
[08:50:32.824] INFO: ----------------------------------------------------------------------
[08:50:32.825] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:50:32.825] INFO: ----------------------------------------------------------------------
[08:50:33.797] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:50:40.451] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[08:50:40.455] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:51:02.793] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 655664 events.
[08:51:15.970] DEBUG: <PixTestXray.cc/processData:L809> # events read: 655664, pixels seen in all events: 12386069
[08:51:16.147] INFO: Resuming triggers.
[08:51:22.803] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[08:51:22.830] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:51:45.128] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 655615 events.
[08:51:58.309] DEBUG: <PixTestXray.cc/processData:L809> # events read: 655615, pixels seen in all events: 12384514
[08:51:58.458] INFO: Resuming triggers.
[08:52:05.114] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[08:52:05.157] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:52:27.721] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 655632 events.
[08:52:41.046] DEBUG: <PixTestXray.cc/processData:L809> # events read: 655632, pixels seen in all events: 12384053
[08:52:41.198] INFO: Resuming triggers.
[08:52:47.856] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[08:52:47.893] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:53:10.525] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 655872 events.
[08:53:22.925] DEBUG: <PixTestXray.cc/processData:L809> # events read: 655872, pixels seen in all events: 12381959
[08:53:23.087] INFO: Resuming triggers.
[08:53:29.743] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[08:53:29.771] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:53:52.392] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 655639 events.
[08:54:04.801] DEBUG: <PixTestXray.cc/processData:L809> # events read: 655639, pixels seen in all events: 12384057
[08:54:04.958] INFO: Resuming triggers.
[08:54:11.616] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[08:54:11.644] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:54:34.014] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 655835 events.
[08:54:46.238] DEBUG: <PixTestXray.cc/processData:L809> # events read: 655835, pixels seen in all events: 12382301
[08:54:46.394] INFO: Resuming triggers.
[08:54:53.051] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[08:54:53.080] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:55:15.237] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 655774 events.
[08:55:27.447] DEBUG: <PixTestXray.cc/processData:L809> # events read: 655774, pixels seen in all events: 12382426
[08:55:27.602] INFO: Resuming triggers.
[08:55:34.262] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[08:55:34.292] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:55:56.568] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 656068 events.
[08:56:08.711] DEBUG: <PixTestXray.cc/processData:L809> # events read: 656068, pixels seen in all events: 12379934
[08:56:08.869] INFO: Resuming triggers.
[08:56:15.529] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[08:56:15.569] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:56:37.735] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 656031 events.
[08:56:49.851] DEBUG: <PixTestXray.cc/processData:L809> # events read: 656031, pixels seen in all events: 12380181
[08:56:50.006] INFO: Resuming triggers.
[08:56:56.669] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[08:56:56.695] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:57:18.923] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 656315 events.
[08:57:31.049] DEBUG: <PixTestXray.cc/processData:L809> # events read: 656315, pixels seen in all events: 12376351
[08:57:31.207] INFO: Resuming triggers.
[08:57:37.868] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[08:57:37.894] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:58:00.071] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 656152 events.
[08:58:12.188] DEBUG: <PixTestXray.cc/processData:L809> # events read: 656152, pixels seen in all events: 12378394
[08:58:12.346] INFO: Resuming triggers.
[08:58:19.004] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[08:58:19.033] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:58:41.198] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 655921 events.
[08:58:53.310] DEBUG: <PixTestXray.cc/processData:L809> # events read: 655921, pixels seen in all events: 12381665
[08:58:53.466] INFO: Resuming triggers.
[08:59:00.127] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[08:59:00.156] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:59:22.469] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 656228 events.
[08:59:34.712] DEBUG: <PixTestXray.cc/processData:L809> # events read: 656228, pixels seen in all events: 12376844
[08:59:34.870] INFO: Resuming triggers.
[08:59:41.529] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[08:59:41.557] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[09:00:03.942] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 656031 events.
[09:00:16.093] DEBUG: <PixTestXray.cc/processData:L809> # events read: 656031, pixels seen in all events: 12380139
[09:00:16.251] INFO: Resuming triggers.
[09:00:22.910] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[09:00:22.940] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[09:00:45.332] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 656046 events.
[09:00:57.573] DEBUG: <PixTestXray.cc/processData:L809> # events read: 656046, pixels seen in all events: 12379783
[09:00:57.733] INFO: Resuming triggers.
[09:00:58.001] INFO: data taking finished, elapsed time: 100 seconds.
[09:00:58.198] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[09:00:59.276] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 26338 events.
[09:00:59.774] DEBUG: <PixTestXray.cc/processData:L809> # events read: 26338, pixels seen in all events: 497439
[09:00:59.808] INFO: PixTest:: pg_setup set to default.
[09:00:59.866] INFO: PixTestXray::doPhRun() done
[09:00:59.866] INFO: PixTestXray::doTest() done
[09:01:32.489] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[09:01:32.489] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 386.7mA
[09:01:32.490] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 471.9mA
[09:01:32.490] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[09:01:32.490] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[09:01:32.490] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[09:01:32.490] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[09:01:32.490] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[09:01:32.490] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[09:01:32.491] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[09:01:32.491] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[09:01:32.491] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[09:01:32.491] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[09:01:32.491] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[09:01:32.491] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[09:01:32.491] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[09:01:32.491] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[09:01:32.491] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[09:01:32.491] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[09:01:32.491] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[09:01:32.492] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[09:01:32.492] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[09:01:32.807] QUIET: Connection to board 58 closed.
[09:01:32.886] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[08:40:24.412] INFO: *** Welcome to pxar ***
[08:40:24.412] INFO: *** Today: 2016/06/02
[08:40:24.435] INFO: *** Version: v1.9.0-805-geb36
[08:40:24.435] INFO: readRocDacs: data/mydnm/dacParameters35_C0.dat .. data/mydnm/dacParameters35_C15.dat
[08:40:24.436] INFO: readTbmDacs: data/mydnm/tbmParameters_C0a.dat .. data/mydnm/tbmParameters_C0b.dat
[08:40:24.436] INFO: readMaskFile: data/mydnm/defaultMaskFile.dat
[08:40:24.436] INFO: readTrimFile: data/mydnm/trimParameters35_C0.dat .. data/mydnm/trimParameters35_C15.dat
[08:40:24.588] INFO: clk: 4
[08:40:24.588] INFO: ctr: 4
[08:40:24.588] INFO: sda: 19
[08:40:24.588] INFO: tin: 9
[08:40:24.588] INFO: level: 15
[08:40:24.588] INFO: triggerdelay: 0
[08:40:24.588] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[08:40:24.588] INFO: Log level: DEBUG
[08:40:24.598] QUIET: Connection to board DTB_WRPRHI opened.
[08:40:24.602] 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:
------------------------------------------------------
[08:40:24.604] INFO: RPC call hashes of host and DTB match: 398089610
[08:40:26.204] INFO: DUT info:
[08:40:26.204] INFO: The DUT currently contains the following objects:
[08:40:26.204] INFO: 2 TBM Cores tbm08c (2 ON)
[08:40:26.204] INFO: TBM Core alpha (0): 7 registers set
[08:40:26.204] INFO: TBM Core beta (1): 7 registers set
[08:40:26.204] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:40:26.204] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:26.204] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[08:40:26.204] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:40:26.204] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:40:26.204] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:40:26.204] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:40:26.204] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[08:40:26.204] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:40:26.205] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[08:40:26.206] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:40:26.207] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:40:26.209] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31485952
[08:40:26.209] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2a1ebd0
[08:40:26.209] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x27c0e60
[08:40:26.209] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f8751d94010
[08:40:26.209] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f8757fff510
[08:40:26.209] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31494144 fPxarMemory = 0x7f8751d94010
[08:40:26.210] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[08:40:26.211] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 470.3mA
[08:40:26.212] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[08:40:26.700] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2e098b0
[08:40:26.792] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[08:40:26.793] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[08:40:26.793] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[08:40:26.798] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[08:40:26.798] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[08:40:26.798] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[08:40:26.809] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[08:40:26.809] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[08:40:26.831] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[08:40:26.831] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[08:40:26.846] INFO: PixTestCmd::init()
[08:40:26.858] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[08:40:26.858] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[08:40:26.858] INFO: readGainPedestalParameters data/mydnm/phCalibrationFitErr35_C0.dat .. data/mydnm/phCalibrationFitErr35_C15.dat
[08:40:27.102] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[08:40:27.102] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[08:40:27.116] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[08:40:27.122] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[08:40:27.122] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[08:40:27.122] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[08:40:27.148] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[08:40:27.148] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[08:40:27.148] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[08:40:27.148] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[08:40:27.148] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[08:40:27.154] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[08:40:27.154] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[08:40:27.176] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[08:40:27.176] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[08:40:27.176] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[08:40:27.176] INFO: readReadbackCal: data/mydnm/readbackCal_C0.dat .. data/mydnm/readbackCal_C15.dat
[08:40:27.185] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[08:40:27.194] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[08:40:27.194] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[08:40:27.194] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[08:40:27.194] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[08:40:27.212] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[08:40:27.212] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[08:42:42.067] INFO: ######################################################################
[08:42:42.067] INFO: PixTestAlive::doTest()
[08:42:42.067] INFO: ######################################################################
[08:42:42.071] INFO: ----------------------------------------------------------------------
[08:42:42.071] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:42:42.071] INFO: ----------------------------------------------------------------------
[08:42:42.073] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:42:42.449] INFO: Expecting 41600 events.
[08:42:46.716] INFO: 41600 events read in total (3549ms).
[08:42:46.864] INFO: Test took 4791ms.
[08:42:46.875] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:46.875] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[08:42:46.875] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[08:42:47.152] INFO: PixTestAlive::aliveTest() done
[08:42:47.152] INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:42:47.152] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 39 26 28 56 66 46 51 48 48 44 63 48 59 47 27 25
[08:42:47.173] INFO: ----------------------------------------------------------------------
[08:42:47.174] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:42:47.174] INFO: ----------------------------------------------------------------------
[08:42:47.176] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:42:47.550] INFO: Expecting 41600 events.
[08:42:50.605] INFO: 41600 events read in total (2340ms).
[08:42:50.605] INFO: Test took 3429ms.
[08:42:50.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:50.605] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[08:42:50.605] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[08:42:50.606] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:42:51.029] INFO: PixTestAlive::maskTest() done
[08:42:51.029] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:42:51.048] INFO: ----------------------------------------------------------------------
[08:42:51.048] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:42:51.048] INFO: ----------------------------------------------------------------------
[08:42:51.050] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:42:51.424] INFO: Expecting 41600 events.
[08:42:55.728] INFO: 41600 events read in total (3589ms).
[08:42:55.730] INFO: Test took 4680ms.
[08:42:55.741] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:42:55.741] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[08:42:55.741] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[08:42:56.112] INFO: PixTestAlive::addressDecodingTest() done
[08:42:56.112] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:42:56.112] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[08:43:26.456] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[08:43:26.456] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[08:43:26.456] INFO: ----------------------------------------------------------------------
[08:43:26.456] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[08:43:26.456] INFO: ----------------------------------------------------------------------
[08:43:26.595] INFO: Expecting 768 events.
[08:43:27.728] INFO: 768 events read in total (417ms).
[08:43:27.728] INFO: Test took 1266ms.
[08:43:27.734] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:43:28.533] INFO: Expecting 41600 events.
[08:43:31.967] INFO: 41600 events read in total (2907ms).
[08:43:31.972] INFO: Test took 4238ms.
[08:43:32.081] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:32.081] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 607211
[08:43:32.081] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[08:43:32.082] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:43:32.128] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:43:32.794] INFO: Expecting 41600 events.
[08:43:36.267] INFO: 41600 events read in total (2947ms).
[08:43:36.271] INFO: Test took 4143ms.
[08:43:36.377] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:36.377] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 615097
[08:43:36.377] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[08:43:36.378] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:43:36.427] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:43:37.092] INFO: Expecting 41600 events.
[08:43:40.586] INFO: 41600 events read in total (2967ms).
[08:43:40.590] INFO: Test took 4163ms.
[08:43:40.696] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:40.696] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 615400
[08:43:40.696] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[08:43:40.697] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:43:40.745] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:43:41.410] INFO: Expecting 41600 events.
[08:43:44.909] INFO: 41600 events read in total (2972ms).
[08:43:44.913] INFO: Test took 4168ms.
[08:43:45.019] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:45.019] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 616108
[08:43:45.019] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[08:43:45.019] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:43:45.069] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:43:45.734] INFO: Expecting 41600 events.
[08:43:49.234] INFO: 41600 events read in total (2974ms).
[08:43:49.238] INFO: Test took 4169ms.
[08:43:49.342] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:49.342] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 615659
[08:43:49.342] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[08:43:49.342] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:43:49.391] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:43:50.060] INFO: Expecting 41600 events.
[08:43:53.547] INFO: 41600 events read in total (2960ms).
[08:43:53.551] INFO: Test took 4160ms.
[08:43:53.656] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:53.656] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 615643
[08:43:53.656] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[08:43:53.656] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:43:53.705] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:43:54.370] INFO: Expecting 41600 events.
[08:43:57.880] INFO: 41600 events read in total (2984ms).
[08:43:57.884] INFO: Test took 4179ms.
[08:43:57.989] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:57.989] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 616976
[08:43:57.990] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[08:43:57.990] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:43:58.038] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:43:58.704] INFO: Expecting 41600 events.
[08:44:02.209] INFO: 41600 events read in total (2979ms).
[08:44:02.213] INFO: Test took 4175ms.
[08:44:02.316] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:02.316] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 614718
[08:44:02.316] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[08:44:02.316] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:44:02.364] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:44:03.030] INFO: Expecting 41600 events.
[08:44:06.540] INFO: 41600 events read in total (2984ms).
[08:44:06.544] INFO: Test took 4180ms.
[08:44:06.647] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:06.647] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 615236
[08:44:06.647] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[08:44:06.648] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:44:06.696] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:44:07.361] INFO: Expecting 41600 events.
[08:44:10.873] INFO: 41600 events read in total (2985ms).
[08:44:10.877] INFO: Test took 4181ms.
[08:44:10.981] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:10.981] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 615841
[08:44:10.981] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[08:44:10.981] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:44:11.029] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:44:11.695] INFO: Expecting 41600 events.
[08:44:15.208] INFO: 41600 events read in total (2987ms).
[08:44:15.212] INFO: Test took 4183ms.
[08:44:15.317] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:15.317] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 616243
[08:44:15.317] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[08:44:15.318] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:44:15.367] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:44:16.032] INFO: Expecting 41600 events.
[08:44:19.547] INFO: 41600 events read in total (2988ms).
[08:44:19.551] INFO: Test took 4184ms.
[08:44:19.657] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:19.657] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 616574
[08:44:19.657] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[08:44:19.658] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:44:19.707] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:44:20.373] INFO: Expecting 41600 events.
[08:44:23.891] INFO: 41600 events read in total (2991ms).
[08:44:23.896] INFO: Test took 4189ms.
[08:44:24.001] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:24.002] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 615567
[08:44:24.002] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[08:44:24.002] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:44:24.051] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:44:24.716] INFO: Expecting 41600 events.
[08:44:28.230] INFO: 41600 events read in total (2987ms).
[08:44:28.235] INFO: Test took 4184ms.
[08:44:28.340] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:28.340] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 616088
[08:44:28.340] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[08:44:28.341] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:44:28.389] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:44:29.054] INFO: Expecting 41600 events.
[08:44:32.568] INFO: 41600 events read in total (2987ms).
[08:44:32.573] INFO: Test took 4184ms.
[08:44:32.677] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:32.677] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 616496
[08:44:32.677] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[08:44:32.677] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:44:32.727] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:44:33.392] INFO: Expecting 41600 events.
[08:44:36.909] INFO: 41600 events read in total (2990ms).
[08:44:36.914] INFO: Test took 4187ms.
[08:44:37.018] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:37.018] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 616165
[08:44:37.018] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[08:44:37.018] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:44:37.067] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:44:37.732] INFO: Expecting 41600 events.
[08:44:41.252] INFO: 41600 events read in total (2993ms).
[08:44:41.257] INFO: Test took 4190ms.
[08:44:41.362] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:41.362] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 617633
[08:44:41.362] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[08:44:41.363] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:44:41.411] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:44:42.077] INFO: Expecting 41600 events.
[08:44:45.591] INFO: 41600 events read in total (2988ms).
[08:44:45.596] INFO: Test took 4185ms.
[08:44:45.699] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:45.699] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 617599
[08:44:45.699] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[08:44:45.700] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:44:45.749] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:44:46.415] INFO: Expecting 41600 events.
[08:44:49.909] INFO: 41600 events read in total (2968ms).
[08:44:49.913] INFO: Test took 4164ms.
[08:44:50.018] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:50.018] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 614194
[08:44:50.018] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[08:44:50.018] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:44:50.067] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:44:50.732] INFO: Expecting 41600 events.
[08:44:54.090] INFO: 41600 events read in total (2831ms).
[08:44:54.094] INFO: Test took 4027ms.
[08:44:54.196] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:44:54.196] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 608259
[08:44:54.196] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[08:44:54.196] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:44:54.468] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 166 eff = 0.998822
[08:44:54.468] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 157 eff = 0.999615
[08:44:54.468] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 153 eff = 0.998942
[08:44:54.468] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 149 eff = 0.998389
[08:44:54.468] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 175 eff = 0.998173
[08:44:54.468] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 155 eff = 0.99851
[08:44:54.469] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 161 eff = 0.998389
[08:44:54.469] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 189 eff = 0.99863
[08:44:54.469] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 161 eff = 0.998221
[08:44:54.469] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 161 eff = 0.998245
[08:44:54.469] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 195 eff = 0.998221
[08:44:54.469] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 180 eff = 0.998413
[08:44:54.469] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 169 eff = 0.998269
[08:44:54.470] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 177 eff = 0.998966
[08:44:54.470] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 148 eff = 0.999567
[08:44:54.470] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 148 eff = 0.999615
[08:44:58.600] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[08:44:58.600] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[08:44:58.600] INFO: ----------------------------------------------------------------------
[08:44:58.600] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[08:44:58.600] INFO: ----------------------------------------------------------------------
[08:44:58.601] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[08:44:58.601] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[08:44:58.601] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[08:44:58.601] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[08:44:58.601] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[08:44:58.601] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[08:44:58.601] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[08:44:58.601] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[08:44:58.601] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[08:44:58.601] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[08:44:58.601] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[08:44:58.601] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[08:44:58.616] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:44:59.282] INFO: Expecting 208000 events.
[08:45:12.940] INFO: 208000 events read in total (13131ms).
[08:45:12.956] INFO: Test took 14339ms.
[08:45:13.442] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:45:13.442] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2789704
[08:45:13.442] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[08:45:13.443] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:45:13.855] INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:45:13.855] INFO: number of red-efficiency pixels: 120 140 214 354 379 353 433 317 358 353 400 366 371 247 119 171
[08:45:13.855] INFO: number of X-ray hits detected: 95033 80213 131683 203985 215156 206445 212837 191054 211056 219305 217794 204401 208705 139174 84860 101446
[08:45:13.855] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[08:45:13.855] INFO: number of Vcal hits detected: 207730 207857 207770 207624 207561 207629 207542 207640 207622 207631 207571 207589 207609 207743 207877 207822
[08:45:13.855] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[08:45:13.855] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[08:45:13.855] INFO: X-ray hit rate [MHz/cm2]: 27.9 23.5 38.6 59.8 63.1 60.5 62.4 56.0 61.9 64.3 63.8 59.9 61.2 40.8 24.9 29.7
[08:45:13.855] INFO: PixTestHighRate::doXPixelAlive() done
[08:45:13.900] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[08:45:13.900] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[08:45:13.900] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[08:45:13.900] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[08:45:13.900] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[08:45:13.900] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[08:45:13.900] INFO: PixTest:: pg_setup set to default.
[08:45:35.864] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[08:45:35.864] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[08:45:36.968] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[08:45:36.969] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 386.7mA
[08:45:36.970] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 470.3mA
[08:45:36.970] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[08:45:36.970] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[08:45:36.970] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[08:45:36.970] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[08:45:36.970] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[08:45:36.970] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[08:45:36.970] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[08:45:36.970] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[08:45:36.970] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[08:45:36.970] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[08:45:36.970] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[08:45:37.058] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[08:45:37.058] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[08:45:37.067] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[08:45:37.068] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[08:45:37.068] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[08:45:37.068] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[08:45:37.068] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[08:45:37.068] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[08:45:37.070] QUIET: Connection to board 58 closed.
[08:45:37.150] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[08:36:53.365] INFO: *** Welcome to pxar ***
[08:36:53.365] INFO: *** Today: 2016/06/02
[08:36:53.389] INFO: *** Version: v1.9.0-805-geb36
[08:36:53.389] INFO: readRocDacs: data/mydnm/dacParameters35_C0.dat .. data/mydnm/dacParameters35_C15.dat
[08:36:53.390] INFO: readTbmDacs: data/mydnm/tbmParameters_C0a.dat .. data/mydnm/tbmParameters_C0b.dat
[08:36:53.390] INFO: readMaskFile: data/mydnm/defaultMaskFile.dat
[08:36:53.390] INFO: readTrimFile: data/mydnm/trimParameters35_C0.dat .. data/mydnm/trimParameters35_C15.dat
[08:36:53.542] INFO: clk: 4
[08:36:53.542] INFO: ctr: 4
[08:36:53.542] INFO: sda: 19
[08:36:53.542] INFO: tin: 9
[08:36:53.542] INFO: level: 15
[08:36:53.542] INFO: triggerdelay: 0
[08:36:53.542] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[08:36:53.542] INFO: Log level: DEBUG
[08:36:53.554] QUIET: Connection to board DTB_WRPRHI opened.
[08:36:53.557] 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:
------------------------------------------------------
[08:36:53.560] INFO: RPC call hashes of host and DTB match: 398089610
[08:36:55.163] INFO: DUT info:
[08:36:55.163] INFO: The DUT currently contains the following objects:
[08:36:55.163] INFO: 2 TBM Cores tbm08c (2 ON)
[08:36:55.164] INFO: TBM Core alpha (0): 7 registers set
[08:36:55.164] INFO: TBM Core beta (1): 7 registers set
[08:36:55.164] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:36:55.164] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[08:36:55.164] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:36:55.165] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:36:55.166] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[08:36:55.167] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[08:36:55.167] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[08:36:55.167] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:36:55.167] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[08:36:55.167] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[08:36:55.167] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[08:36:55.167] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[08:36:55.167] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:36:55.167] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:36:55.169] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32755712
[08:36:55.169] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1829bd0
[08:36:55.169] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x15cbe60
[08:36:55.169] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f17d9d94010
[08:36:55.169] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f17dffff510
[08:36:55.169] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32763904 fPxarMemory = 0x7f17d9d94010
[08:36:55.170] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[08:36:55.171] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 470.3mA
[08:36:55.171] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[08:36:55.686] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1c14910
[08:36:55.751] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[08:36:55.751] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[08:36:55.751] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[08:36:55.757] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[08:36:55.757] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[08:36:55.757] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[08:36:55.767] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[08:36:55.768] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[08:36:55.775] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[08:36:55.775] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[08:36:55.789] INFO: PixTestCmd::init()
[08:36:55.800] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[08:36:55.800] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[08:36:55.800] INFO: readGainPedestalParameters data/mydnm/phCalibrationFitErr35_C0.dat .. data/mydnm/phCalibrationFitErr35_C15.dat
[08:36:56.051] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[08:36:56.051] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[08:36:56.066] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[08:36:56.073] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[08:36:56.073] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[08:36:56.073] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[08:36:56.096] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[08:36:56.096] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[08:36:56.097] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[08:36:56.097] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[08:36:56.097] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[08:36:56.109] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[08:36:56.109] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[08:36:56.138] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[08:36:56.138] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[08:36:56.138] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[08:36:56.138] INFO: readReadbackCal: data/mydnm/readbackCal_C0.dat .. data/mydnm/readbackCal_C15.dat
[08:36:56.163] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[08:36:56.178] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[08:36:56.178] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[08:36:56.178] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[08:36:56.178] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[08:36:56.199] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[08:36:56.199] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[08:37:05.348] INFO: ######################################################################
[08:37:05.348] INFO: PixTestAlive::doTest()
[08:37:05.348] INFO: ######################################################################
[08:37:05.352] INFO: ----------------------------------------------------------------------
[08:37:05.352] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:37:05.352] INFO: ----------------------------------------------------------------------
[08:37:05.354] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:37:05.740] INFO: Expecting 41600 events.
[08:37:10.031] INFO: 41600 events read in total (3572ms).
[08:37:10.174] INFO: Test took 4820ms.
[08:37:10.184] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:10.184] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[08:37:10.184] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[08:37:10.472] INFO: PixTestAlive::aliveTest() done
[08:37:10.472] INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:37:10.472] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 43 26 49 81 92 60 91 70 92 65 72 83 66 52 29 36
[08:37:10.503] INFO: ----------------------------------------------------------------------
[08:37:10.503] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:37:10.503] INFO: ----------------------------------------------------------------------
[08:37:10.505] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:37:10.880] INFO: Expecting 41600 events.
[08:37:13.952] INFO: 41600 events read in total (2357ms).
[08:37:13.952] INFO: Test took 3447ms.
[08:37:13.952] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:13.952] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[08:37:13.952] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[08:37:13.953] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:37:14.377] INFO: PixTestAlive::maskTest() done
[08:37:14.377] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:37:14.396] INFO: ----------------------------------------------------------------------
[08:37:14.397] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:37:14.397] INFO: ----------------------------------------------------------------------
[08:37:14.399] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:37:14.774] INFO: Expecting 41600 events.
[08:37:19.072] INFO: 41600 events read in total (3583ms).
[08:37:19.074] INFO: Test took 4675ms.
[08:37:19.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:19.086] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[08:37:19.086] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[08:37:19.454] INFO: PixTestAlive::addressDecodingTest() done
[08:37:19.455] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:37:19.455] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[08:37:48.232] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[08:37:48.232] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[08:37:48.232] INFO: ----------------------------------------------------------------------
[08:37:48.233] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[08:37:48.233] INFO: ----------------------------------------------------------------------
[08:37:48.372] INFO: Expecting 768 events.
[08:37:49.504] INFO: 768 events read in total (417ms).
[08:37:49.505] INFO: Test took 1267ms.
[08:37:49.510] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:37:50.308] INFO: Expecting 41600 events.
[08:37:54.009] INFO: 41600 events read in total (3174ms).
[08:37:54.015] INFO: Test took 4505ms.
[08:37:54.170] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:54.170] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 863306
[08:37:54.170] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[08:37:54.171] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:37:54.243] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:37:54.911] INFO: Expecting 41600 events.
[08:37:58.796] INFO: 41600 events read in total (3358ms).
[08:37:58.803] INFO: Test took 4560ms.
[08:37:58.961] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:58.961] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 872347
[08:37:58.961] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[08:37:58.961] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:37:59.029] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:37:59.709] INFO: Expecting 41600 events.
[08:38:03.591] INFO: 41600 events read in total (3355ms).
[08:38:03.597] INFO: Test took 4568ms.
[08:38:03.742] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:03.742] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 873304
[08:38:03.742] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[08:38:03.742] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:38:03.809] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:38:04.476] INFO: Expecting 41600 events.
[08:38:08.357] INFO: 41600 events read in total (3354ms).
[08:38:08.363] INFO: Test took 4554ms.
[08:38:08.519] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:08.519] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 874823
[08:38:08.519] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[08:38:08.519] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:38:08.589] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:38:09.258] INFO: Expecting 41600 events.
[08:38:13.124] INFO: 41600 events read in total (3339ms).
[08:38:13.129] INFO: Test took 4540ms.
[08:38:13.271] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:13.271] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 876661
[08:38:13.271] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[08:38:13.272] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:38:13.338] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:38:14.005] INFO: Expecting 41600 events.
[08:38:17.845] INFO: 41600 events read in total (3313ms).
[08:38:17.850] INFO: Test took 4512ms.
[08:38:17.998] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:17.998] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 875012
[08:38:17.998] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[08:38:17.998] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:38:18.066] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:38:18.732] INFO: Expecting 41600 events.
[08:38:22.585] INFO: 41600 events read in total (3326ms).
[08:38:22.590] INFO: Test took 4524ms.
[08:38:22.736] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:22.736] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 875182
[08:38:22.736] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[08:38:22.736] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:38:22.803] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:38:23.469] INFO: Expecting 41600 events.
[08:38:27.286] INFO: 41600 events read in total (3290ms).
[08:38:27.292] INFO: Test took 4489ms.
[08:38:27.434] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:27.434] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 875170
[08:38:27.434] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[08:38:27.435] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:38:27.501] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:38:28.167] INFO: Expecting 41600 events.
[08:38:32.026] INFO: 41600 events read in total (3332ms).
[08:38:32.031] INFO: Test took 4530ms.
[08:38:32.177] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:32.177] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 878097
[08:38:32.177] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[08:38:32.177] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:38:32.246] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:38:32.911] INFO: Expecting 41600 events.
[08:38:36.740] INFO: 41600 events read in total (3302ms).
[08:38:36.745] INFO: Test took 4499ms.
[08:38:36.888] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:36.888] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 877034
[08:38:36.888] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[08:38:36.889] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:38:36.956] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:38:37.623] INFO: Expecting 41600 events.
[08:38:41.435] INFO: 41600 events read in total (3286ms).
[08:38:41.440] INFO: Test took 4484ms.
[08:38:41.583] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:41.583] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 876530
[08:38:41.583] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[08:38:41.584] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:38:41.651] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:38:42.317] INFO: Expecting 41600 events.
[08:38:46.129] INFO: 41600 events read in total (3285ms).
[08:38:46.135] INFO: Test took 4484ms.
[08:38:46.277] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:46.277] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 876124
[08:38:46.277] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[08:38:46.278] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:38:46.344] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:38:47.010] INFO: Expecting 41600 events.
[08:38:50.832] INFO: 41600 events read in total (3296ms).
[08:38:50.838] INFO: Test took 4493ms.
[08:38:50.980] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:50.981] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 875769
[08:38:50.981] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[08:38:50.981] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:38:51.048] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:38:51.713] INFO: Expecting 41600 events.
[08:38:55.517] INFO: 41600 events read in total (3277ms).
[08:38:55.522] INFO: Test took 4474ms.
[08:38:55.665] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:38:55.665] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 875990
[08:38:55.665] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[08:38:55.666] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:38:55.734] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:38:56.399] INFO: Expecting 41600 events.
[08:39:00.209] INFO: 41600 events read in total (3283ms).
[08:39:00.214] INFO: Test took 4480ms.
[08:39:00.357] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:00.357] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 875608
[08:39:00.357] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[08:39:00.358] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:39:00.424] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:39:01.090] INFO: Expecting 41600 events.
[08:39:04.908] INFO: 41600 events read in total (3291ms).
[08:39:04.914] INFO: Test took 4490ms.
[08:39:05.055] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:05.055] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 874770
[08:39:05.055] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[08:39:05.055] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:39:05.121] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:39:05.786] INFO: Expecting 41600 events.
[08:39:09.590] INFO: 41600 events read in total (3277ms).
[08:39:09.596] INFO: Test took 4474ms.
[08:39:09.738] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:09.738] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 876405
[08:39:09.738] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[08:39:09.738] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:39:09.805] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:39:10.470] INFO: Expecting 41600 events.
[08:39:14.281] INFO: 41600 events read in total (3284ms).
[08:39:14.286] INFO: Test took 4481ms.
[08:39:14.428] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:14.428] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 873093
[08:39:14.429] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[08:39:14.429] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:39:14.496] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:39:15.162] INFO: Expecting 41600 events.
[08:39:18.940] INFO: 41600 events read in total (3251ms).
[08:39:18.945] INFO: Test took 4449ms.
[08:39:19.089] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:19.089] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 874338
[08:39:19.089] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[08:39:19.090] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:39:19.157] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:39:19.823] INFO: Expecting 41600 events.
[08:39:23.497] INFO: 41600 events read in total (3148ms).
[08:39:23.502] INFO: Test took 4345ms.
[08:39:23.644] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:23.644] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 869046
[08:39:23.644] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[08:39:23.644] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:39:23.933] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 166 eff = 0.998462
[08:39:23.934] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 160 eff = 0.999399
[08:39:23.934] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 155 eff = 0.997933
[08:39:23.934] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 156 eff = 0.996226
[08:39:23.934] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 175 eff = 0.995889
[08:39:23.935] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 155 eff = 0.995938
[08:39:23.935] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 162 eff = 0.996394
[08:39:23.935] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 185 eff = 0.996803
[08:39:23.935] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 160 eff = 0.996298
[08:39:23.936] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 166 eff = 0.995745
[08:39:23.936] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 195 eff = 0.995481
[08:39:23.936] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 178 eff = 0.99649
[08:39:23.936] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 172 eff = 0.996298
[08:39:23.937] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 175 eff = 0.997861
[08:39:23.937] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 150 eff = 0.999111
[08:39:23.937] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 155 eff = 0.99899
[08:39:39.928] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[08:39:39.928] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[08:39:39.928] INFO: ----------------------------------------------------------------------
[08:39:39.928] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[08:39:39.928] INFO: ----------------------------------------------------------------------
[08:39:39.929] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[08:39:39.929] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[08:39:39.929] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[08:39:39.929] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[08:39:39.929] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[08:39:39.929] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[08:39:39.929] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[08:39:39.929] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[08:39:39.929] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[08:39:39.929] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[08:39:39.929] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[08:39:39.929] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[08:39:39.944] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:39:40.613] INFO: Expecting 208000 events.
[08:39:56.486] INFO: 208000 events read in total (15347ms).
[08:39:56.506] INFO: Test took 16562ms.
[08:39:57.211] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:39:57.211] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4063537
[08:39:57.211] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[08:39:57.211] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:39:57.707] INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:39:57.707] INFO: number of red-efficiency pixels: 212 204 414 679 795 710 727 629 744 817 809 683 686 396 183 278
[08:39:57.707] INFO: number of X-ray hits detected: 139162 118093 193373 300331 316333 302304 311460 280569 311432 320387 319616 299256 305597 204742 124347 149978
[08:39:57.707] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[08:39:57.707] INFO: number of Vcal hits detected: 207634 207788 207525 207197 207019 207186 207177 207292 207143 206955 207058 207222 207181 207556 207811 207708
[08:39:57.707] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.6 99.6 99.6 99.6 99.7 99.6 99.5 99.6 99.7 99.6 99.8 99.9 99.9
[08:39:57.707] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.6 99.5 99.6 99.6 99.7 99.6 99.5 99.5 99.6 99.6 99.8 99.9 99.9
[08:39:57.707] INFO: X-ray hit rate [MHz/cm2]: 40.8 34.6 56.7 88.0 92.7 88.6 91.3 82.2 91.3 93.9 93.7 87.7 89.6 60.0 36.4 44.0
[08:39:57.707] INFO: PixTestHighRate::doXPixelAlive() done
[08:39:57.753] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[08:39:57.753] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[08:39:57.754] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[08:39:57.754] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[08:39:57.754] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[08:39:57.754] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[08:39:57.754] INFO: PixTest:: pg_setup set to default.
[08:40:11.464] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[08:40:11.464] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[08:40:12.456] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[08:40:12.457] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 386.7mA
[08:40:12.458] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 471.1mA
[08:40:12.458] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[08:40:12.458] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[08:40:12.458] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[08:40:12.458] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[08:40:12.458] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[08:40:12.458] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[08:40:12.458] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[08:40:12.458] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[08:40:12.458] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[08:40:12.458] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[08:40:12.458] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[08:40:12.547] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[08:40:12.547] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[08:40:12.556] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[08:40:12.556] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[08:40:12.556] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[08:40:12.556] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[08:40:12.557] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[08:40:12.557] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[08:40:12.559] QUIET: Connection to board 58 closed.
[08:40:12.639] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[08:32:47.083] INFO: *** Welcome to pxar ***
[08:32:47.083] INFO: *** Today: 2016/06/02
[08:32:47.107] INFO: *** Version: v1.9.0-805-geb36
[08:32:47.107] INFO: readRocDacs: data/mydnm/dacParameters35_C0.dat .. data/mydnm/dacParameters35_C15.dat
[08:32:47.108] INFO: readTbmDacs: data/mydnm/tbmParameters_C0a.dat .. data/mydnm/tbmParameters_C0b.dat
[08:32:47.108] INFO: readMaskFile: data/mydnm/defaultMaskFile.dat
[08:32:47.109] INFO: readTrimFile: data/mydnm/trimParameters35_C0.dat .. data/mydnm/trimParameters35_C15.dat
[08:32:47.257] INFO: clk: 4
[08:32:47.258] INFO: ctr: 4
[08:32:47.258] INFO: sda: 19
[08:32:47.258] INFO: tin: 9
[08:32:47.258] INFO: level: 15
[08:32:47.258] INFO: triggerdelay: 0
[08:32:47.258] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[08:32:47.258] INFO: Log level: DEBUG
[08:32:47.268] QUIET: Connection to board DTB_WRPRHI opened.
[08:32:47.272] 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:
------------------------------------------------------
[08:32:47.274] INFO: RPC call hashes of host and DTB match: 398089610
[08:32:48.875] INFO: DUT info:
[08:32:48.875] INFO: The DUT currently contains the following objects:
[08:32:48.875] INFO: 2 TBM Cores tbm08c (2 ON)
[08:32:48.875] INFO: TBM Core alpha (0): 7 registers set
[08:32:48.875] INFO: TBM Core beta (1): 7 registers set
[08:32:48.875] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:32:48.875] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.875] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.875] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.875] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.876] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.876] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.876] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.876] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.876] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.876] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.876] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.876] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.876] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.876] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.876] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.876] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:32:48.876] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[08:32:48.877] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:32:48.878] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:32:48.881] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31395840
[08:32:48.881] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1948bd0
[08:32:48.881] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x16eae60
[08:32:48.881] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f1d1dd94010
[08:32:48.881] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f1d23fff510
[08:32:48.881] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31404032 fPxarMemory = 0x7f1d1dd94010
[08:32:48.882] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[08:32:48.883] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 470.3mA
[08:32:48.883] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[08:32:49.375] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1d338c0
[08:32:49.435] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[08:32:49.435] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[08:32:49.435] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[08:32:49.441] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[08:32:49.441] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[08:32:49.441] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[08:32:49.450] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[08:32:49.450] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[08:32:49.480] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[08:32:49.480] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[08:32:49.492] INFO: PixTestCmd::init()
[08:32:49.510] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[08:32:49.510] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[08:32:49.510] INFO: readGainPedestalParameters data/mydnm/phCalibrationFitErr35_C0.dat .. data/mydnm/phCalibrationFitErr35_C15.dat
[08:32:49.754] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[08:32:49.754] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[08:32:49.769] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[08:32:49.775] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[08:32:49.775] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[08:32:49.775] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[08:32:49.798] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[08:32:49.798] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[08:32:49.798] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[08:32:49.798] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[08:32:49.798] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[08:32:49.804] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[08:32:49.804] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[08:32:49.818] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[08:32:49.819] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[08:32:49.819] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[08:32:49.819] INFO: readReadbackCal: data/mydnm/readbackCal_C0.dat .. data/mydnm/readbackCal_C15.dat
[08:32:49.833] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[08:32:49.841] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[08:32:49.842] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[08:32:49.842] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[08:32:49.842] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[08:32:49.861] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[08:32:49.862] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[08:32:53.883] INFO: ######################################################################
[08:32:53.883] INFO: PixTestAlive::doTest()
[08:32:53.883] INFO: ######################################################################
[08:32:53.887] INFO: ----------------------------------------------------------------------
[08:32:53.887] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:32:53.887] INFO: ----------------------------------------------------------------------
[08:32:53.889] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:32:54.264] INFO: Expecting 41600 events.
[08:32:58.580] INFO: 41600 events read in total (3597ms).
[08:32:58.728] INFO: Test took 4839ms.
[08:32:58.739] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:58.739] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[08:32:58.739] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[08:32:59.031] INFO: PixTestAlive::aliveTest() done
[08:32:59.031] INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:32:59.031] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 51 33 65 105 99 83 103 103 89 118 119 108 107 74 53 47
[08:32:59.058] INFO: ----------------------------------------------------------------------
[08:32:59.058] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:32:59.058] INFO: ----------------------------------------------------------------------
[08:32:59.061] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:32:59.435] INFO: Expecting 41600 events.
[08:33:02.511] INFO: 41600 events read in total (2360ms).
[08:33:02.511] INFO: Test took 3450ms.
[08:33:02.511] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:02.511] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[08:33:02.511] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[08:33:02.512] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:33:02.934] INFO: PixTestAlive::maskTest() done
[08:33:02.934] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:33:02.961] INFO: ----------------------------------------------------------------------
[08:33:02.961] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:33:02.961] INFO: ----------------------------------------------------------------------
[08:33:02.964] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:33:03.338] INFO: Expecting 41600 events.
[08:33:07.621] INFO: 41600 events read in total (3567ms).
[08:33:07.623] INFO: Test took 4659ms.
[08:33:07.634] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:07.634] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[08:33:07.634] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[08:33:08.005] INFO: PixTestAlive::addressDecodingTest() done
[08:33:08.005] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:33:08.005] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[08:33:21.680] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[08:33:21.680] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[08:33:21.680] INFO: ----------------------------------------------------------------------
[08:33:21.680] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[08:33:21.680] INFO: ----------------------------------------------------------------------
[08:33:21.820] INFO: Expecting 768 events.
[08:33:22.953] INFO: 768 events read in total (418ms).
[08:33:22.953] INFO: Test took 1267ms.
[08:33:22.959] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:33:23.757] INFO: Expecting 41600 events.
[08:33:27.790] INFO: 41600 events read in total (3506ms).
[08:33:27.798] INFO: Test took 4839ms.
[08:33:27.997] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:27.997] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1124834
[08:33:27.997] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[08:33:27.997] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:33:28.076] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:33:28.743] INFO: Expecting 41600 events.
[08:33:32.912] INFO: 41600 events read in total (3643ms).
[08:33:32.918] INFO: Test took 4842ms.
[08:33:33.107] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:33.107] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1131695
[08:33:33.107] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[08:33:33.108] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:33:33.190] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:33:33.855] INFO: Expecting 41600 events.
[08:33:37.969] INFO: 41600 events read in total (3587ms).
[08:33:37.975] INFO: Test took 4785ms.
[08:33:38.170] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:38.170] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1134445
[08:33:38.170] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[08:33:38.171] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:33:38.257] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:33:38.925] INFO: Expecting 41600 events.
[08:33:43.093] INFO: 41600 events read in total (3642ms).
[08:33:43.099] INFO: Test took 4842ms.
[08:33:43.285] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:43.285] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1132466
[08:33:43.285] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[08:33:43.285] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:33:43.371] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:33:44.038] INFO: Expecting 41600 events.
[08:33:48.215] INFO: 41600 events read in total (3650ms).
[08:33:48.222] INFO: Test took 4851ms.
[08:33:48.409] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:48.443] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1133626
[08:33:48.443] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[08:33:48.444] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:33:48.528] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:33:49.194] INFO: Expecting 41600 events.
[08:33:53.350] INFO: 41600 events read in total (3630ms).
[08:33:53.356] INFO: Test took 4828ms.
[08:33:53.546] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:53.546] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1135430
[08:33:53.546] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[08:33:53.546] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:33:53.632] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:33:54.299] INFO: Expecting 41600 events.
[08:33:58.463] INFO: 41600 events read in total (3638ms).
[08:33:58.469] INFO: Test took 4837ms.
[08:33:58.661] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:58.661] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1134605
[08:33:58.661] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[08:33:58.661] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:33:58.745] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:33:59.411] INFO: Expecting 41600 events.
[08:34:03.600] INFO: 41600 events read in total (3664ms).
[08:34:03.607] INFO: Test took 4862ms.
[08:34:03.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:03.796] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1133764
[08:34:03.796] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[08:34:03.796] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:34:03.879] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:34:04.544] INFO: Expecting 41600 events.
[08:34:08.760] INFO: 41600 events read in total (3690ms).
[08:34:08.768] INFO: Test took 4889ms.
[08:34:08.958] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:08.958] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1135029
[08:34:08.958] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[08:34:08.958] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:34:09.043] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:34:09.709] INFO: Expecting 41600 events.
[08:34:13.938] INFO: 41600 events read in total (3702ms).
[08:34:13.948] INFO: Test took 4905ms.
[08:34:14.138] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:14.138] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1135790
[08:34:14.138] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[08:34:14.139] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:34:14.224] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:34:14.890] INFO: Expecting 41600 events.
[08:34:19.073] INFO: 41600 events read in total (3657ms).
[08:34:19.079] INFO: Test took 4855ms.
[08:34:19.270] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:19.270] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1133557
[08:34:19.271] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[08:34:19.271] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:34:19.355] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:34:20.022] INFO: Expecting 41600 events.
[08:34:24.246] INFO: 41600 events read in total (3697ms).
[08:34:24.253] INFO: Test took 4898ms.
[08:34:24.506] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:24.506] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1133608
[08:34:24.506] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[08:34:24.507] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:34:24.634] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:34:25.302] INFO: Expecting 41600 events.
[08:34:29.497] INFO: 41600 events read in total (3668ms).
[08:34:29.505] INFO: Test took 4871ms.
[08:34:29.709] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:29.709] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1133224
[08:34:29.709] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[08:34:29.709] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:34:29.796] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:34:30.468] INFO: Expecting 41600 events.
[08:34:34.699] INFO: 41600 events read in total (3705ms).
[08:34:34.706] INFO: Test took 4910ms.
[08:34:34.903] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:34.903] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1133995
[08:34:34.903] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[08:34:34.904] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:34:34.988] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:34:35.653] INFO: Expecting 41600 events.
[08:34:39.846] INFO: 41600 events read in total (3666ms).
[08:34:39.853] INFO: Test took 4865ms.
[08:34:40.055] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:40.055] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1132356
[08:34:40.055] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[08:34:40.056] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:34:40.141] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:34:40.807] INFO: Expecting 41600 events.
[08:34:44.984] INFO: 41600 events read in total (3651ms).
[08:34:44.991] INFO: Test took 4850ms.
[08:34:45.182] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:45.182] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1132325
[08:34:45.182] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[08:34:45.182] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:34:45.267] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:34:45.932] INFO: Expecting 41600 events.
[08:34:50.091] INFO: 41600 events read in total (3632ms).
[08:34:50.098] INFO: Test took 4831ms.
[08:34:50.287] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:50.287] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1133650
[08:34:50.287] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[08:34:50.288] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:34:50.374] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:34:51.040] INFO: Expecting 41600 events.
[08:34:55.205] INFO: 41600 events read in total (3638ms).
[08:34:55.211] INFO: Test took 4837ms.
[08:34:55.400] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:55.400] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1133127
[08:34:55.400] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[08:34:55.400] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:34:55.484] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:34:56.150] INFO: Expecting 41600 events.
[08:35:00.287] INFO: 41600 events read in total (3610ms).
[08:35:00.293] INFO: Test took 4809ms.
[08:35:00.480] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:00.480] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1130480
[08:35:00.480] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[08:35:00.481] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:35:00.565] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:35:01.231] INFO: Expecting 41600 events.
[08:35:05.225] INFO: 41600 events read in total (3467ms).
[08:35:05.231] INFO: Test took 4666ms.
[08:35:05.423] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:05.423] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1124951
[08:35:05.423] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[08:35:05.423] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:35:05.728] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 171 eff = 0.996514
[08:35:05.728] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 167 eff = 0.997452
[08:35:05.728] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 159 eff = 0.994904
[08:35:05.728] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 155 eff = 0.991707
[08:35:05.728] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 171 eff = 0.990096
[08:35:05.729] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 158 eff = 0.990697
[08:35:05.729] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 167 eff = 0.990769
[08:35:05.729] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 186 eff = 0.992476
[08:35:05.730] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 161 eff = 0.990385
[08:35:05.730] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 164 eff = 0.98887
[08:35:05.730] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 195 eff = 0.989615
[08:35:05.730] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 180 eff = 0.990649
[08:35:05.730] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 166 eff = 0.989447
[08:35:05.731] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 175 eff = 0.993942
[08:35:05.731] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 153 eff = 0.996851
[08:35:05.731] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 166 eff = 0.996514
[08:35:28.368] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[08:35:28.368] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[08:35:28.368] INFO: ----------------------------------------------------------------------
[08:35:28.368] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[08:35:28.368] INFO: ----------------------------------------------------------------------
[08:35:28.369] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[08:35:28.369] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[08:35:28.369] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[08:35:28.369] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[08:35:28.369] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[08:35:28.369] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[08:35:28.369] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[08:35:28.369] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[08:35:28.369] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[08:35:28.369] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[08:35:28.369] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[08:35:28.369] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[08:35:28.384] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:35:29.050] INFO: Expecting 208000 events.
[08:35:47.450] INFO: 208000 events read in total (17873ms).
[08:35:47.477] INFO: Test took 19093ms.
[08:35:48.536] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:48.536] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 5338847
[08:35:48.537] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[08:35:48.537] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:35:49.108] INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:35:49.108] INFO: number of red-efficiency pixels: 282 289 664 1189 1273 1253 1307 1043 1246 1408 1464 1250 1302 707 288 315
[08:35:49.108] INFO: number of X-ray hits detected: 182951 156474 255125 392318 416788 400092 410242 371319 410641 422730 421224 395402 403385 270374 164960 198265
[08:35:49.108] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[08:35:49.108] INFO: number of Vcal hits detected: 207554 207685 207153 206490 206348 206436 206276 206706 206460 206151 206048 206352 206280 207094 207687 207665
[08:35:49.108] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.6 99.3 99.3 99.3 99.2 99.4 99.3 99.2 99.1 99.3 99.3 99.6 99.9 99.8
[08:35:49.108] INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.6 99.3 99.2 99.2 99.2 99.4 99.3 99.1 99.1 99.2 99.2 99.6 99.8 99.8
[08:35:49.108] INFO: X-ray hit rate [MHz/cm2]: 53.6 45.9 74.8 115.0 122.2 117.3 120.2 108.8 120.4 123.9 123.5 115.9 118.2 79.2 48.4 58.1
[08:35:49.108] INFO: PixTestHighRate::doXPixelAlive() done
[08:35:49.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[08:35:49.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[08:35:49.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[08:35:49.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[08:35:49.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[08:35:49.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[08:35:49.154] INFO: PixTest:: pg_setup set to default.
[08:36:36.736] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[08:36:36.736] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[08:36:39.040] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[08:36:39.041] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 386.7mA
[08:36:39.042] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 471.1mA
[08:36:39.042] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[08:36:39.042] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[08:36:39.042] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[08:36:39.042] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[08:36:39.042] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[08:36:39.042] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[08:36:39.042] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[08:36:39.042] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[08:36:39.042] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[08:36:39.042] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[08:36:39.042] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[08:36:39.137] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[08:36:39.138] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[08:36:39.147] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[08:36:39.147] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[08:36:39.147] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[08:36:39.147] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[08:36:39.147] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[08:36:39.147] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[08:36:39.151] QUIET: Connection to board 58 closed.
[08:36:39.231] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master