Test Date: 1973-11-29 16:33
Analysis date: 2016-06-28 14:26
Logfile
hrData_40.log
[11:26:00.148] INFO: *** Welcome to pxar ***
[11:26:00.148] INFO: *** Today: 2016/06/27
[11:26:00.175] INFO: *** Version: v1.9.0-814-g7497
[11:26:00.175] INFO: readRocDacs: data/myinq/dacParameters35_C0.dat .. data/myinq/dacParameters35_C15.dat
[11:26:00.176] INFO: readTbmDacs: data/myinq/tbmParameters_C0a.dat .. data/myinq/tbmParameters_C0b.dat
[11:26:00.176] INFO: readMaskFile: data/myinq/defaultMaskFile.dat
[11:26:00.176] INFO: readTrimFile: data/myinq/trimParameters35_C0.dat .. data/myinq/trimParameters35_C15.dat
[11:26:00.325] INFO: clk: 4
[11:26:00.325] INFO: ctr: 4
[11:26:00.325] INFO: sda: 19
[11:26:00.325] INFO: tin: 9
[11:26:00.325] INFO: level: 15
[11:26:00.325] INFO: triggerdelay: 0
[11:26:00.325] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[11:26:00.325] INFO: Log level: DEBUG
[11:26:00.336] QUIET: Connection to board DTB_WRMNMR opened.
[11:26:00.339] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[11:26:00.342] INFO: RPC call hashes of host and DTB match: 398089610
[11:26:01.942] INFO: DUT info:
[11:26:01.942] INFO: The DUT currently contains the following objects:
[11:26:01.942] INFO: 2 TBM Cores tbm08c (2 ON)
[11:26:01.942] INFO: TBM Core alpha (0): 7 registers set
[11:26:01.942] INFO: TBM Core beta (1): 7 registers set
[11:26:01.942] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:26:01.942] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.942] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.942] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.942] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.942] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.942] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.942] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.943] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.943] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.943] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.943] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.943] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.943] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.943] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.943] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.943] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:26:01.943] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:26:01.944] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:26:01.945] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:26:01.946] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:26:01.948] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33210368
[11:26:01.949] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1d1e0d0
[11:26:01.949] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1ac0360
[11:26:01.949] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f845dd94010
[11:26:01.949] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f8463fff510
[11:26:01.949] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33218560 fPxarMemory = 0x7f845dd94010
[11:26:01.950] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 384.3mA
[11:26:01.951] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 475.9mA
[11:26:01.951] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[11:26:02.447] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2108d50
[11:26:02.507] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:26:02.507] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:26:02.508] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:26:02.513] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:26:02.513] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:26:02.513] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:26:02.525] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:26:02.525] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:26:02.548] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:26:02.548] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:26:02.558] INFO: PixTestCmd::init()
[11:26:02.565] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:26:02.565] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:26:02.565] INFO: readGainPedestalParameters data/myinq/phCalibrationFitErr35_C0.dat .. data/myinq/phCalibrationFitErr35_C15.dat
[11:26:02.827] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:26:02.828] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:26:02.842] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:26:02.848] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:26:02.848] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:26:02.848] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:26:02.868] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:26:02.868] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:26:02.868] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:26:02.868] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:26:02.868] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:26:02.874] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:26:02.874] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:26:02.888] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:26:02.888] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:26:02.888] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:26:02.888] INFO: readReadbackCal: data/myinq/readbackCal_C0.dat .. data/myinq/readbackCal_C15.dat
[11:26:02.899] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:26:02.906] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[11:26:02.906] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[11:26:02.906] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[11:26:02.906] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[11:26:02.919] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:26:02.919] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:26:07.385] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[11:26:11.897] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[11:26:16.094] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[11:26:16.094] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[11:26:16.094] INFO: ----------------------------------------------------------------------
[11:26:16.094] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[11:26:16.094] INFO: ----------------------------------------------------------------------
[11:26:17.067] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[11:26:29.096] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[11:26:29.100] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:26:56.362] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1185165 events.
[11:27:02.891] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1185165, pixels seen in all events: 6030350
[11:27:02.985] INFO: Resuming triggers.
[11:27:15.018] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[11:27:15.043] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:27:42.094] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1185487 events.
[11:27:48.609] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1185487, pixels seen in all events: 6025722
[11:27:48.778] INFO: Resuming triggers.
[11:28:00.811] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[11:28:00.838] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:28:27.847] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1185410 events.
[11:28:34.391] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1185410, pixels seen in all events: 6026056
[11:28:34.647] INFO: Resuming triggers.
[11:28:46.680] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[11:28:46.705] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:29:13.582] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1185399 events.
[11:29:20.099] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1185399, pixels seen in all events: 6026501
[11:29:20.350] INFO: Resuming triggers.
[11:29:32.305] INFO: data taking finished, elapsed time: 60 seconds.
[11:29:32.501] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:29:59.349] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1177773 events.
[11:30:05.834] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1177773, pixels seen in all events: 5981966
[11:30:06.004] INFO: PixTest:: pg_setup set to default.
[11:30:06.042] INFO: PixTestXray::doPhRun() done
[13:10:46.119] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[13:10:46.120] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hMap_02ma_C8_V0 -> hMap_02ma_mod
[13:10:50.912] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[13:10:50.912] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 388.3mA
[13:10:50.913] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 475.9mA
[13:10:50.913] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[13:10:50.913] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[13:10:50.913] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[13:10:50.913] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[13:10:50.913] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[13:10:50.913] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[13:10:50.913] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[13:10:50.914] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[13:10:50.914] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[13:10:50.914] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[13:10:50.914] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[13:10:50.914] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[13:10:50.914] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[13:10:50.914] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[13:10:50.914] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[13:10:50.914] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[13:10:50.914] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[13:10:50.914] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[13:10:50.914] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[13:10:51.201] QUIET: Connection to board 43 closed.
[13:10:51.281] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[13:26:56.672] INFO: *** Welcome to pxar ***
[13:26:56.672] INFO: *** Today: 2016/06/27
[13:26:56.696] INFO: *** Version: v1.9.0-814-g7497
[13:26:56.696] INFO: readRocDacs: data/myinq/dacParameters35_C0.dat .. data/myinq/dacParameters35_C15.dat
[13:26:56.697] INFO: readTbmDacs: data/myinq/tbmParameters_C0a.dat .. data/myinq/tbmParameters_C0b.dat
[13:26:56.697] INFO: readMaskFile: data/myinq/defaultMaskFile.dat
[13:26:56.697] INFO: readTrimFile: data/myinq/trimParameters35_C0.dat .. data/myinq/trimParameters35_C15.dat
[13:26:56.847] INFO: clk: 4
[13:26:56.847] INFO: ctr: 4
[13:26:56.847] INFO: sda: 19
[13:26:56.847] INFO: tin: 9
[13:26:56.847] INFO: level: 15
[13:26:56.847] INFO: triggerdelay: 0
[13:26:56.847] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[13:26:56.847] INFO: Log level: DEBUG
[13:26:56.953] QUIET: Connection to board DTB_WRMNMR opened.
[13:26:56.956] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[13:26:56.959] INFO: RPC call hashes of host and DTB match: 398089610
[13:26:58.560] INFO: DUT info:
[13:26:58.560] INFO: The DUT currently contains the following objects:
[13:26:58.560] INFO: 2 TBM Cores tbm08c (2 ON)
[13:26:58.561] INFO: TBM Core alpha (0): 7 registers set
[13:26:58.561] INFO: TBM Core beta (1): 7 registers set
[13:26:58.561] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:26:58.561] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:58.561] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[13:26:58.561] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:26:58.561] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:26:58.561] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[13:26:58.562] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[13:26:58.563] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:26:58.564] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:26:58.566] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30367744
[13:26:58.566] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2b640d0
[13:26:58.566] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2906360
[13:26:58.566] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f6d39d94010
[13:26:58.566] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f6d3ffff510
[13:26:58.566] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30375936 fPxarMemory = 0x7f6d39d94010
[13:26:58.567] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 382.7mA
[13:26:58.569] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 474.3mA
[13:26:58.569] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -1.3 C
[13:26:59.107] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2f4ed60
[13:26:59.173] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[13:26:59.173] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[13:26:59.173] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[13:26:59.179] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[13:26:59.179] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[13:26:59.179] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[13:26:59.191] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[13:26:59.191] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[13:26:59.215] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[13:26:59.216] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[13:26:59.230] INFO: PixTestCmd::init()
[13:26:59.243] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[13:26:59.243] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[13:26:59.243] INFO: readGainPedestalParameters data/myinq/phCalibrationFitErr35_C0.dat .. data/myinq/phCalibrationFitErr35_C15.dat
[13:26:59.503] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[13:26:59.504] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[13:26:59.519] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[13:26:59.527] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[13:26:59.527] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[13:26:59.527] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[13:26:59.548] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[13:26:59.548] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[13:26:59.548] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[13:26:59.548] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[13:26:59.548] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[13:26:59.554] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[13:26:59.554] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[13:26:59.575] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[13:26:59.575] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[13:26:59.575] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[13:26:59.575] INFO: readReadbackCal: data/myinq/readbackCal_C0.dat .. data/myinq/readbackCal_C15.dat
[13:26:59.587] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[13:26:59.596] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[13:26:59.596] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[13:26:59.596] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[13:26:59.596] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[13:26:59.613] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[13:26:59.613] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[13:27:06.971] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[13:27:08.843] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[13:27:10.304] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[13:27:10.304] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[13:27:10.304] INFO: ----------------------------------------------------------------------
[13:27:10.304] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[13:27:10.304] INFO: ----------------------------------------------------------------------
[13:27:11.278] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[13:27:18.440] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[13:27:18.444] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:27:43.581] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 705704 events.
[13:27:56.109] DEBUG: <PixTestXray.cc/processData:L823> # events read: 705704, pixels seen in all events: 11784782
[13:27:56.321] INFO: Resuming triggers.
[13:28:03.483] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[13:28:03.513] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:28:28.586] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 705641 events.
[13:28:41.065] DEBUG: <PixTestXray.cc/processData:L823> # events read: 705641, pixels seen in all events: 11784243
[13:28:41.260] INFO: Resuming triggers.
[13:28:48.428] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[13:28:48.455] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:29:13.470] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 706132 events.
[13:29:25.902] DEBUG: <PixTestXray.cc/processData:L823> # events read: 706132, pixels seen in all events: 11777811
[13:29:26.077] INFO: Resuming triggers.
[13:29:33.243] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[13:29:33.288] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:29:58.312] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 705958 events.
[13:30:10.796] DEBUG: <PixTestXray.cc/processData:L823> # events read: 705958, pixels seen in all events: 11781026
[13:30:10.991] INFO: Resuming triggers.
[13:30:18.159] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[13:30:18.188] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:30:43.204] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 706155 events.
[13:30:55.660] DEBUG: <PixTestXray.cc/processData:L823> # events read: 706155, pixels seen in all events: 11777672
[13:30:55.851] INFO: Resuming triggers.
[13:31:03.014] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[13:31:03.041] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:31:27.972] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 705625 events.
[13:31:40.485] DEBUG: <PixTestXray.cc/processData:L823> # events read: 705625, pixels seen in all events: 11785244
[13:31:40.678] INFO: Resuming triggers.
[13:31:47.846] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[13:31:47.876] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:32:12.930] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 706159 events.
[13:32:25.443] DEBUG: <PixTestXray.cc/processData:L823> # events read: 706159, pixels seen in all events: 11778026
[13:32:25.637] INFO: Resuming triggers.
[13:32:32.805] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[13:32:32.833] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:32:57.763] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 706081 events.
[13:33:10.268] DEBUG: <PixTestXray.cc/processData:L823> # events read: 706081, pixels seen in all events: 11779661
[13:33:10.463] INFO: Resuming triggers.
[13:33:13.220] INFO: data taking finished, elapsed time: 60 seconds.
[13:33:13.416] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:33:23.225] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 271544 events.
[13:33:28.019] DEBUG: <PixTestXray.cc/processData:L823> # events read: 271544, pixels seen in all events: 4529340
[13:33:28.090] INFO: PixTest:: pg_setup set to default.
[13:33:28.130] INFO: PixTestXray::doPhRun() done
[13:33:47.944] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[13:33:47.945] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 386.7mA
[13:33:47.945] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 476.7mA
[13:33:47.945] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[13:33:47.945] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[13:33:47.946] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[13:33:47.946] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[13:33:47.946] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[13:33:47.946] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[13:33:47.946] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[13:33:47.946] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[13:33:47.946] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[13:33:47.946] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[13:33:47.946] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[13:33:47.946] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[13:33:47.946] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[13:33:47.946] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[13:33:47.947] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[13:33:47.947] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[13:33:47.947] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[13:33:47.947] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[13:33:47.947] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[13:33:48.258] QUIET: Connection to board 43 closed.
[13:33:48.338] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[11:20:10.605] INFO: *** Welcome to pxar ***
[11:20:10.605] INFO: *** Today: 2016/06/27
[11:20:10.629] INFO: *** Version: v1.9.0-814-g7497
[11:20:10.630] INFO: readRocDacs: data/myinq/dacParameters35_C0.dat .. data/myinq/dacParameters35_C15.dat
[11:20:10.631] INFO: readTbmDacs: data/myinq/tbmParameters_C0a.dat .. data/myinq/tbmParameters_C0b.dat
[11:20:10.631] INFO: readMaskFile: data/myinq/defaultMaskFile.dat
[11:20:10.631] INFO: readTrimFile: data/myinq/trimParameters35_C0.dat .. data/myinq/trimParameters35_C15.dat
[11:20:10.779] INFO: clk: 4
[11:20:10.779] INFO: ctr: 4
[11:20:10.779] INFO: sda: 19
[11:20:10.779] INFO: tin: 9
[11:20:10.779] INFO: level: 15
[11:20:10.779] INFO: triggerdelay: 0
[11:20:10.780] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[11:20:10.780] INFO: Log level: DEBUG
[11:20:10.791] QUIET: Connection to board DTB_WRMNMR opened.
[11:20:10.794] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[11:20:10.797] INFO: RPC call hashes of host and DTB match: 398089610
[11:20:12.399] INFO: DUT info:
[11:20:12.399] INFO: The DUT currently contains the following objects:
[11:20:12.399] INFO: 2 TBM Cores tbm08c (2 ON)
[11:20:12.399] INFO: TBM Core alpha (0): 7 registers set
[11:20:12.399] INFO: TBM Core beta (1): 7 registers set
[11:20:12.399] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:20:12.399] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.399] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.399] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.399] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.399] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.399] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.399] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.399] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.399] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.399] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.399] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.399] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.399] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.399] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.399] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.399] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:20:12.400] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:20:12.401] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:20:12.402] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:20:12.403] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:20:12.405] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34840576
[11:20:12.405] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1eda0d0
[11:20:12.405] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1c7c360
[11:20:12.405] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f12ad393010
[11:20:12.405] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f12b35fe510
[11:20:12.405] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34848768 fPxarMemory = 0x7f12ad393010
[11:20:12.407] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 384.3mA
[11:20:12.408] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 475.9mA
[11:20:12.408] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[11:20:12.907] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x22c4d50
[11:20:12.970] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:20:12.970] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:20:12.970] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:20:12.976] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:20:12.976] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:20:12.976] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:20:12.986] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:20:12.987] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:20:13.000] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:20:13.002] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:20:13.026] INFO: PixTestCmd::init()
[11:20:13.038] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:20:13.038] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:20:13.038] INFO: readGainPedestalParameters data/myinq/phCalibrationFitErr35_C0.dat .. data/myinq/phCalibrationFitErr35_C15.dat
[11:20:13.283] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:20:13.284] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:20:13.302] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:20:13.310] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:20:13.310] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:20:13.310] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:20:13.333] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:20:13.333] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:20:13.333] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:20:13.333] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:20:13.333] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:20:13.339] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:20:13.340] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:20:13.356] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:20:13.356] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:20:13.357] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:20:13.357] INFO: readReadbackCal: data/myinq/readbackCal_C0.dat .. data/myinq/readbackCal_C15.dat
[11:20:13.366] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:20:13.374] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[11:20:13.374] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[11:20:13.375] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[11:20:13.375] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[11:20:13.390] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:20:13.390] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:20:17.281] INFO: ######################################################################
[11:20:17.281] INFO: PixTestAlive::doTest()
[11:20:17.281] INFO: ######################################################################
[11:20:17.285] INFO: ----------------------------------------------------------------------
[11:20:17.285] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:20:17.285] INFO: ----------------------------------------------------------------------
[11:20:17.287] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:20:17.666] INFO: Expecting 41600 events.
[11:20:21.879] INFO: 41600 events read in total (3494ms).
[11:20:22.026] INFO: Test took 4739ms.
[11:20:22.037] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:22.037] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[11:20:22.037] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[11:20:22.313] INFO: PixTestAlive::aliveTest() done
[11:20:22.313] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0
[11:20:22.313] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 9 16 28 42 39 38 37 33 46 54 35 51 45 34 13 5
[11:20:22.338] INFO: ----------------------------------------------------------------------
[11:20:22.338] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:20:22.338] INFO: ----------------------------------------------------------------------
[11:20:22.342] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:20:22.719] INFO: Expecting 41600 events.
[11:20:25.642] INFO: 41600 events read in total (2207ms).
[11:20:25.642] INFO: Test took 3300ms.
[11:20:25.642] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:25.642] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[11:20:25.642] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[11:20:25.643] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:20:26.065] INFO: PixTestAlive::maskTest() done
[11:20:26.065] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:20:26.087] INFO: ----------------------------------------------------------------------
[11:20:26.087] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:20:26.087] INFO: ----------------------------------------------------------------------
[11:20:26.089] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:20:26.466] INFO: Expecting 41600 events.
[11:20:30.677] INFO: 41600 events read in total (3495ms).
[11:20:30.678] INFO: Test took 4589ms.
[11:20:30.689] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:30.689] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[11:20:30.689] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[11:20:31.059] INFO: PixTestAlive::addressDecodingTest() done
[11:20:31.059] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:20:31.059] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[11:20:40.342] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:20:40.342] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C3_V0 -> PixelAlive_mod
[11:20:42.950] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[11:20:42.950] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[11:20:42.950] INFO: ----------------------------------------------------------------------
[11:20:42.950] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:20:42.950] INFO: ----------------------------------------------------------------------
[11:20:43.089] INFO: Expecting 768 events.
[11:20:44.218] INFO: 768 events read in total (414ms).
[11:20:44.218] INFO: Test took 1262ms.
[11:20:44.224] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:20:45.022] INFO: Expecting 41600 events.
[11:20:48.267] INFO: 41600 events read in total (2719ms).
[11:20:48.272] INFO: Test took 4048ms.
[11:20:48.362] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:48.362] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 510720
[11:20:48.362] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[11:20:48.362] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:20:48.403] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:20:49.071] INFO: Expecting 41600 events.
[11:20:52.413] INFO: 41600 events read in total (2816ms).
[11:20:52.417] INFO: Test took 4014ms.
[11:20:52.502] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:52.502] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 516624
[11:20:52.502] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[11:20:52.503] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:20:52.544] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:20:53.209] INFO: Expecting 41600 events.
[11:20:56.602] INFO: 41600 events read in total (2866ms).
[11:20:56.606] INFO: Test took 4062ms.
[11:20:56.693] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:56.693] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 520000
[11:20:56.693] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[11:20:56.693] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:20:56.735] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:20:57.400] INFO: Expecting 41600 events.
[11:21:00.798] INFO: 41600 events read in total (2872ms).
[11:21:00.802] INFO: Test took 4067ms.
[11:21:00.887] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:00.887] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 520545
[11:21:00.887] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[11:21:00.887] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:00.929] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:01.594] INFO: Expecting 41600 events.
[11:21:04.968] INFO: 41600 events read in total (2848ms).
[11:21:04.972] INFO: Test took 4043ms.
[11:21:05.057] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:05.057] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 520976
[11:21:05.057] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[11:21:05.058] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:05.100] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:05.767] INFO: Expecting 41600 events.
[11:21:09.137] INFO: 41600 events read in total (2843ms).
[11:21:09.141] INFO: Test took 4041ms.
[11:21:09.226] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:09.227] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 520228
[11:21:09.227] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[11:21:09.227] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:09.269] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:09.934] INFO: Expecting 41600 events.
[11:21:13.348] INFO: 41600 events read in total (2887ms).
[11:21:13.352] INFO: Test took 4083ms.
[11:21:13.437] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:13.437] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 520734
[11:21:13.437] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[11:21:13.438] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:13.481] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:14.146] INFO: Expecting 41600 events.
[11:21:17.564] INFO: 41600 events read in total (2891ms).
[11:21:17.568] INFO: Test took 4087ms.
[11:21:17.653] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:17.653] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 519733
[11:21:17.653] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[11:21:17.653] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:17.695] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:18.360] INFO: Expecting 41600 events.
[11:21:21.784] INFO: 41600 events read in total (2897ms).
[11:21:21.787] INFO: Test took 4093ms.
[11:21:21.874] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:21.874] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 522512
[11:21:21.874] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[11:21:21.874] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:21.916] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:22.581] INFO: Expecting 41600 events.
[11:21:25.987] INFO: 41600 events read in total (2879ms).
[11:21:25.991] INFO: Test took 4075ms.
[11:21:26.076] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:26.076] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 520197
[11:21:26.076] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[11:21:26.076] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:26.119] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:26.784] INFO: Expecting 41600 events.
[11:21:30.217] INFO: 41600 events read in total (2907ms).
[11:21:30.220] INFO: Test took 4102ms.
[11:21:30.307] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:30.307] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 523070
[11:21:30.307] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[11:21:30.307] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:30.349] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:31.014] INFO: Expecting 41600 events.
[11:21:34.419] INFO: 41600 events read in total (2878ms).
[11:21:34.423] INFO: Test took 4074ms.
[11:21:34.509] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:34.509] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 520675
[11:21:34.510] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[11:21:34.510] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:34.552] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:35.218] INFO: Expecting 41600 events.
[11:21:38.638] INFO: 41600 events read in total (2893ms).
[11:21:38.642] INFO: Test took 4090ms.
[11:21:38.727] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:38.727] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 521292
[11:21:38.727] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[11:21:38.727] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:38.770] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:39.435] INFO: Expecting 41600 events.
[11:21:42.861] INFO: 41600 events read in total (2899ms).
[11:21:42.865] INFO: Test took 4095ms.
[11:21:42.950] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:42.950] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 522537
[11:21:42.950] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[11:21:42.951] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:42.993] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:43.658] INFO: Expecting 41600 events.
[11:21:47.068] INFO: 41600 events read in total (2883ms).
[11:21:47.072] INFO: Test took 4079ms.
[11:21:47.157] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:47.157] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 519972
[11:21:47.157] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[11:21:47.157] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:47.200] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:47.865] INFO: Expecting 41600 events.
[11:21:51.294] INFO: 41600 events read in total (2903ms).
[11:21:51.298] INFO: Test took 4098ms.
[11:21:51.384] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:51.384] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 522373
[11:21:51.384] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[11:21:51.384] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:51.427] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:52.092] INFO: Expecting 41600 events.
[11:21:55.513] INFO: 41600 events read in total (2894ms).
[11:21:55.517] INFO: Test took 4090ms.
[11:21:55.602] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:55.602] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 520768
[11:21:55.602] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[11:21:55.603] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:55.645] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:56.310] INFO: Expecting 41600 events.
[11:21:59.733] INFO: 41600 events read in total (2896ms).
[11:21:59.736] INFO: Test took 4091ms.
[11:21:59.822] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:59.822] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 521186
[11:21:59.822] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[11:21:59.822] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:59.864] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:22:00.529] INFO: Expecting 41600 events.
[11:22:03.913] INFO: 41600 events read in total (2857ms).
[11:22:03.917] INFO: Test took 4053ms.
[11:22:04.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:04.002] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 520771
[11:22:04.002] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[11:22:04.002] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:22:04.045] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:22:04.710] INFO: Expecting 41600 events.
[11:22:07.929] INFO: 41600 events read in total (2693ms).
[11:22:07.933] INFO: Test took 3888ms.
[11:22:08.018] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:08.018] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 513139
[11:22:08.018] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[11:22:08.018] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:22:08.281] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 130 eff = 0.999952
[11:22:08.281] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 132 eff = 0.99976
[11:22:08.281] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 164 eff = 0.999255
[11:22:08.281] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 140 eff = 0.99887
[11:22:08.282] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 163 eff = 0.998534
[11:22:08.282] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 145 eff = 0.998486
[11:22:08.282] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 146 eff = 0.998558
[11:22:08.282] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 152 eff = 0.998606
[11:22:08.282] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 121 eff = 0.998317
[11:22:08.282] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 164 eff = 0.998654
[11:22:08.282] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 146 eff = 0.998438
[11:22:08.283] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 143 eff = 0.962428
[11:22:08.283] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 164 eff = 0.998582
[11:22:08.283] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 155 eff = 0.99899
[11:22:08.283] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 130 eff = 0.999712
[11:22:08.283] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 132 eff = 0.999976
[11:22:10.758] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[11:22:10.758] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[11:22:10.758] INFO: ----------------------------------------------------------------------
[11:22:10.758] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:22:10.758] INFO: ----------------------------------------------------------------------
[11:22:10.758] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[11:22:10.758] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[11:22:10.758] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[11:22:10.758] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[11:22:10.758] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[11:22:10.758] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[11:22:10.758] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[11:22:10.758] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[11:22:10.758] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[11:22:10.758] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[11:22:10.758] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[11:22:10.759] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[11:22:10.777] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:22:11.446] INFO: Expecting 208000 events.
[11:22:25.146] INFO: 208000 events read in total (13174ms).
[11:22:25.159] INFO: Test took 14382ms.
[11:22:25.575] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:25.575] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2323931
[11:22:25.575] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[11:22:25.576] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:22:25.953] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 4 0 1 0 0
[11:22:25.953] INFO: number of red-efficiency pixels: 39 93 172 352 327 292 336 331 420 333 354 556 307 167 112 13
[11:22:25.953] INFO: number of X-ray hits detected: 23652 63520 112636 174599 188565 188858 191915 176389 196334 196385 186875 175289 187795 117034 67182 10348
[11:22:25.953] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:22:25.953] INFO: number of Vcal hits detected: 207961 207905 207819 207637 207654 207689 207646 207652 207553 207652 207626 200059 207682 207775 207884 207987
[11:22:25.953] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.8 99.8 99.9 99.8 99.8 99.8 99.8 99.8 96.2 99.9 99.9 99.9 100.0
[11:22:25.953] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.8 99.8 99.9 99.8 99.8 99.8 99.8 99.8 96.2 99.8 99.9 99.9 100.0
[11:22:25.953] INFO: X-ray hit rate [MHz/cm2]: 6.9 18.6 33.0 51.2 55.3 55.4 56.3 51.7 57.5 57.6 54.8 51.4 55.0 34.3 19.7 3.0
[11:22:25.953] INFO: PixTestHighRate::doXPixelAlive() done
[11:22:25.998] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[11:22:25.998] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[11:22:25.998] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[11:22:25.998] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[11:22:25.998] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[11:22:25.998] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[11:22:25.999] INFO: PixTest:: pg_setup set to default.
[11:23:11.734] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:23:11.734] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[11:23:13.158] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:23:13.159] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 387.5mA
[11:23:13.159] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 476.7mA
[11:23:13.159] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[11:23:13.159] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:23:13.160] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:23:13.160] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:23:13.160] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:23:13.160] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:23:13.160] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:23:13.160] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:23:13.160] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:23:13.160] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:23:13.160] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:23:13.249] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:23:13.249] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:23:13.263] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:23:13.263] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:23:13.263] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:23:13.263] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[11:23:13.263] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:23:13.263] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:23:13.266] QUIET: Connection to board 43 closed.
[11:23:13.345] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[11:17:27.033] INFO: *** Welcome to pxar ***
[11:17:27.033] INFO: *** Today: 2016/06/27
[11:17:27.058] INFO: *** Version: v1.9.0-814-g7497
[11:17:27.058] INFO: readRocDacs: data/myinq/dacParameters35_C0.dat .. data/myinq/dacParameters35_C15.dat
[11:17:27.059] INFO: readTbmDacs: data/myinq/tbmParameters_C0a.dat .. data/myinq/tbmParameters_C0b.dat
[11:17:27.059] INFO: readMaskFile: data/myinq/defaultMaskFile.dat
[11:17:27.059] INFO: readTrimFile: data/myinq/trimParameters35_C0.dat .. data/myinq/trimParameters35_C15.dat
[11:17:27.211] INFO: clk: 4
[11:17:27.211] INFO: ctr: 4
[11:17:27.211] INFO: sda: 19
[11:17:27.211] INFO: tin: 9
[11:17:27.211] INFO: level: 15
[11:17:27.211] INFO: triggerdelay: 0
[11:17:27.211] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[11:17:27.211] INFO: Log level: DEBUG
[11:17:27.222] QUIET: Connection to board DTB_WRMNMR opened.
[11:17:27.225] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[11:17:27.228] INFO: RPC call hashes of host and DTB match: 398089610
[11:17:28.830] INFO: DUT info:
[11:17:28.830] INFO: The DUT currently contains the following objects:
[11:17:28.830] INFO: 2 TBM Cores tbm08c (2 ON)
[11:17:28.830] INFO: TBM Core alpha (0): 7 registers set
[11:17:28.830] INFO: TBM Core beta (1): 7 registers set
[11:17:28.830] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:17:28.830] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.830] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.830] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.830] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.830] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.830] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.830] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.830] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.830] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.830] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.830] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.830] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.830] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.830] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.830] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.830] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:17:28.831] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:17:28.832] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:17:28.833] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:17:28.834] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:17:28.836] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33263616
[11:17:28.836] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x27110d0
[11:17:28.836] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x24b3360
[11:17:28.836] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7ff6edd94010
[11:17:28.836] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7ff6f3fff510
[11:17:28.836] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33271808 fPxarMemory = 0x7ff6edd94010
[11:17:28.837] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 384.3mA
[11:17:28.839] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 475.9mA
[11:17:28.839] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[11:17:29.330] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2afbc90
[11:17:29.392] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:17:29.392] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:17:29.392] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:17:29.398] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:17:29.398] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:17:29.398] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:17:29.411] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:17:29.411] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:17:29.429] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:17:29.429] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:17:29.446] INFO: PixTestCmd::init()
[11:17:29.460] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:17:29.460] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:17:29.460] INFO: readGainPedestalParameters data/myinq/phCalibrationFitErr35_C0.dat .. data/myinq/phCalibrationFitErr35_C15.dat
[11:17:29.711] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:17:29.711] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:17:29.725] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:17:29.731] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:17:29.731] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:17:29.731] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:17:29.753] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:17:29.753] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:17:29.753] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:17:29.753] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:17:29.753] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:17:29.759] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:17:29.759] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:17:29.775] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:17:29.775] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:17:29.775] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:17:29.775] INFO: readReadbackCal: data/myinq/readbackCal_C0.dat .. data/myinq/readbackCal_C15.dat
[11:17:29.786] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:17:29.796] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[11:17:29.796] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[11:17:29.796] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[11:17:29.796] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[11:17:29.814] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:17:29.815] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:17:34.993] INFO: ######################################################################
[11:17:34.993] INFO: PixTestAlive::doTest()
[11:17:34.993] INFO: ######################################################################
[11:17:34.996] INFO: ----------------------------------------------------------------------
[11:17:34.996] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:17:34.997] INFO: ----------------------------------------------------------------------
[11:17:34.999] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:17:35.377] INFO: Expecting 41600 events.
[11:17:39.618] INFO: 41600 events read in total (3522ms).
[11:17:39.766] INFO: Test took 4767ms.
[11:17:39.778] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:39.778] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[11:17:39.778] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[11:17:40.052] INFO: PixTestAlive::aliveTest() done
[11:17:40.052] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0
[11:17:40.052] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 7 35 47 77 67 77 95 70 77 79 92 70 72 56 26 5
[11:17:40.081] INFO: ----------------------------------------------------------------------
[11:17:40.081] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:17:40.081] INFO: ----------------------------------------------------------------------
[11:17:40.083] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:17:40.462] INFO: Expecting 41600 events.
[11:17:43.398] INFO: 41600 events read in total (2221ms).
[11:17:43.398] INFO: Test took 3315ms.
[11:17:43.398] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:43.398] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[11:17:43.399] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[11:17:43.399] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:17:43.821] INFO: PixTestAlive::maskTest() done
[11:17:43.821] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:17:43.843] INFO: ----------------------------------------------------------------------
[11:17:43.843] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:17:43.843] INFO: ----------------------------------------------------------------------
[11:17:43.846] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:17:44.225] INFO: Expecting 41600 events.
[11:17:48.429] INFO: 41600 events read in total (3489ms).
[11:17:48.431] INFO: Test took 4585ms.
[11:17:48.442] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:48.442] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[11:17:48.442] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[11:17:48.812] INFO: PixTestAlive::addressDecodingTest() done
[11:17:48.812] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:17:48.812] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[11:18:00.206] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:18:00.206] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C3_V0 -> PixelAlive_mod
[11:18:02.470] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[11:18:02.470] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[11:18:02.470] INFO: ----------------------------------------------------------------------
[11:18:02.470] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:18:02.470] INFO: ----------------------------------------------------------------------
[11:18:02.609] INFO: Expecting 768 events.
[11:18:03.741] INFO: 768 events read in total (415ms).
[11:18:03.741] INFO: Test took 1265ms.
[11:18:03.747] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:18:04.544] INFO: Expecting 41600 events.
[11:18:08.193] INFO: 41600 events read in total (3122ms).
[11:18:08.198] INFO: Test took 4451ms.
[11:18:08.347] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:08.347] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 842007
[11:18:08.347] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[11:18:08.347] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:18:08.409] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:18:09.076] INFO: Expecting 41600 events.
[11:18:12.820] INFO: 41600 events read in total (3217ms).
[11:18:12.825] INFO: Test took 4416ms.
[11:18:12.962] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:12.962] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 850170
[11:18:12.962] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[11:18:12.962] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:18:13.028] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:18:13.694] INFO: Expecting 41600 events.
[11:18:17.475] INFO: 41600 events read in total (3255ms).
[11:18:17.480] INFO: Test took 4452ms.
[11:18:17.622] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:17.622] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 851687
[11:18:17.622] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[11:18:17.622] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:18:17.688] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:18:18.354] INFO: Expecting 41600 events.
[11:18:22.141] INFO: 41600 events read in total (3261ms).
[11:18:22.146] INFO: Test took 4458ms.
[11:18:22.284] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:22.284] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 851192
[11:18:22.284] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[11:18:22.285] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:18:22.351] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:18:23.017] INFO: Expecting 41600 events.
[11:18:26.817] INFO: 41600 events read in total (3274ms).
[11:18:26.822] INFO: Test took 4471ms.
[11:18:26.961] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:26.961] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 849750
[11:18:26.961] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[11:18:26.961] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:18:27.026] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:18:27.691] INFO: Expecting 41600 events.
[11:18:31.486] INFO: 41600 events read in total (3268ms).
[11:18:31.492] INFO: Test took 4466ms.
[11:18:31.632] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:31.632] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 853474
[11:18:31.632] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[11:18:31.632] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:18:31.699] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:18:32.366] INFO: Expecting 41600 events.
[11:18:36.187] INFO: 41600 events read in total (3294ms).
[11:18:36.192] INFO: Test took 4493ms.
[11:18:36.333] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:36.333] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 850520
[11:18:36.333] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[11:18:36.333] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:18:36.398] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:18:37.063] INFO: Expecting 41600 events.
[11:18:40.862] INFO: 41600 events read in total (3272ms).
[11:18:40.867] INFO: Test took 4469ms.
[11:18:41.006] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:41.006] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 852548
[11:18:41.006] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[11:18:41.006] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:18:41.071] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:18:41.736] INFO: Expecting 41600 events.
[11:18:45.547] INFO: 41600 events read in total (3285ms).
[11:18:45.552] INFO: Test took 4481ms.
[11:18:45.690] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:45.690] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 850838
[11:18:45.690] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[11:18:45.690] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:18:45.755] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:18:46.420] INFO: Expecting 41600 events.
[11:18:50.238] INFO: 41600 events read in total (3291ms).
[11:18:50.243] INFO: Test took 4488ms.
[11:18:50.382] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:50.382] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 852000
[11:18:50.382] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[11:18:50.382] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:18:50.447] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:18:51.112] INFO: Expecting 41600 events.
[11:18:54.916] INFO: 41600 events read in total (3277ms).
[11:18:54.921] INFO: Test took 4474ms.
[11:18:55.058] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:55.058] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 851477
[11:18:55.059] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[11:18:55.059] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:18:55.125] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:18:55.790] INFO: Expecting 41600 events.
[11:18:59.616] INFO: 41600 events read in total (3299ms).
[11:18:59.621] INFO: Test took 4496ms.
[11:18:59.759] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:18:59.759] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 852447
[11:18:59.759] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[11:18:59.759] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:18:59.824] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:19:00.489] INFO: Expecting 41600 events.
[11:19:04.299] INFO: 41600 events read in total (3283ms).
[11:19:04.305] INFO: Test took 4481ms.
[11:19:04.443] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:04.443] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 853325
[11:19:04.443] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[11:19:04.444] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:19:04.509] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:19:05.174] INFO: Expecting 41600 events.
[11:19:08.986] INFO: 41600 events read in total (3285ms).
[11:19:08.991] INFO: Test took 4482ms.
[11:19:09.129] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:09.129] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 851943
[11:19:09.129] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[11:19:09.130] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:19:09.195] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:19:09.860] INFO: Expecting 41600 events.
[11:19:13.706] INFO: 41600 events read in total (3320ms).
[11:19:13.711] INFO: Test took 4516ms.
[11:19:13.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:13.850] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 851137
[11:19:13.850] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[11:19:13.851] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:19:13.917] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:19:14.582] INFO: Expecting 41600 events.
[11:19:18.376] INFO: 41600 events read in total (3267ms).
[11:19:18.382] INFO: Test took 4465ms.
[11:19:18.521] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:18.521] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 851541
[11:19:18.521] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[11:19:18.522] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:19:18.587] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:19:19.252] INFO: Expecting 41600 events.
[11:19:23.048] INFO: 41600 events read in total (3270ms).
[11:19:23.053] INFO: Test took 4466ms.
[11:19:23.191] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:23.191] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 851726
[11:19:23.191] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[11:19:23.191] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:19:23.257] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:19:23.922] INFO: Expecting 41600 events.
[11:19:27.714] INFO: 41600 events read in total (3266ms).
[11:19:27.720] INFO: Test took 4463ms.
[11:19:27.859] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:27.859] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 852214
[11:19:27.859] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[11:19:27.860] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:19:27.925] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:19:28.590] INFO: Expecting 41600 events.
[11:19:32.360] INFO: 41600 events read in total (3243ms).
[11:19:32.365] INFO: Test took 4440ms.
[11:19:32.505] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:32.505] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 849266
[11:19:32.505] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[11:19:32.506] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:19:32.570] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:19:33.236] INFO: Expecting 41600 events.
[11:19:36.803] INFO: 41600 events read in total (3040ms).
[11:19:36.808] INFO: Test took 4238ms.
[11:19:36.945] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:36.945] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 839462
[11:19:36.945] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[11:19:36.946] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:19:37.231] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 139 eff = 0.999856
[11:19:37.232] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 148 eff = 0.999279
[11:19:37.232] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 164 eff = 0.997813
[11:19:37.232] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 154 eff = 0.996418
[11:19:37.232] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 163 eff = 0.995529
[11:19:37.232] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 145 eff = 0.995745
[11:19:37.232] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 146 eff = 0.995481
[11:19:37.232] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 155 eff = 0.99625
[11:19:37.233] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 142 eff = 0.995072
[11:19:37.233] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 164 eff = 0.995
[11:19:37.233] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 173 eff = 0.995457
[11:19:37.233] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 161 eff = 0.957019
[11:19:37.233] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 164 eff = 0.995481
[11:19:37.233] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 157 eff = 0.997957
[11:19:37.233] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 147 eff = 0.999303
[11:19:37.234] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 157 eff = 0.999976
[11:19:39.254] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[11:19:39.254] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[11:19:39.254] INFO: ----------------------------------------------------------------------
[11:19:39.254] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:19:39.254] INFO: ----------------------------------------------------------------------
[11:19:39.254] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[11:19:39.254] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[11:19:39.254] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[11:19:39.254] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[11:19:39.254] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[11:19:39.254] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[11:19:39.254] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[11:19:39.254] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[11:19:39.254] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[11:19:39.254] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[11:19:39.254] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[11:19:39.254] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[11:19:39.271] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:19:39.939] INFO: Expecting 208000 events.
[11:19:56.273] INFO: 208000 events read in total (15807ms).
[11:19:56.293] INFO: Test took 17021ms.
[11:19:56.976] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:19:56.976] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3945964
[11:19:56.976] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[11:19:56.977] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:19:57.462] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 118 0 1 0 0
[11:19:57.462] INFO: number of red-efficiency pixels: 57 159 414 719 819 830 886 777 913 934 823 972 831 392 195 19
[11:19:57.462] INFO: number of X-ray hits detected: 41254 109885 194819 300988 325352 323959 329946 303597 337066 337187 319642 299299 322165 201032 115359 17973
[11:19:57.462] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:19:57.462] INFO: number of Vcal hits detected: 207941 207831 207527 207191 207071 207046 206957 207097 206934 206845 207025 199061 207025 207511 207799 207979
[11:19:57.462] INFO: Vcal hit fiducial efficiency (%): 100.0 99.9 99.8 99.6 99.6 99.6 99.5 99.6 99.5 99.5 99.6 98.5 99.6 99.8 99.9 100.0
[11:19:57.462] INFO: Vcal hit overall efficiency (%): 100.0 99.9 99.8 99.6 99.6 99.5 99.5 99.6 99.5 99.4 99.5 95.7 99.5 99.8 99.9 100.0
[11:19:57.462] INFO: X-ray hit rate [MHz/cm2]: 12.1 32.2 57.1 88.2 95.4 95.0 96.7 89.0 98.8 98.8 93.7 87.7 94.4 58.9 33.8 5.3
[11:19:57.462] INFO: PixTestHighRate::doXPixelAlive() done
[11:19:57.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[11:19:57.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[11:19:57.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[11:19:57.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[11:19:57.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[11:19:57.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[11:19:57.507] INFO: PixTest:: pg_setup set to default.
[11:20:02.894] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:20:02.894] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[11:20:04.686] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:20:04.687] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 386.7mA
[11:20:04.687] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 476.7mA
[11:20:04.687] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[11:20:04.687] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:20:04.687] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:20:04.688] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:20:04.688] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:20:04.688] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:20:04.688] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:20:04.688] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:20:04.688] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:20:04.688] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:20:04.688] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:20:04.781] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:20:04.781] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:20:04.794] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:20:04.794] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:20:04.794] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:20:04.795] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[11:20:04.795] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:20:04.795] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:20:04.797] QUIET: Connection to board 43 closed.
[11:20:04.877] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[11:14:13.706] INFO: *** Welcome to pxar ***
[11:14:13.706] INFO: *** Today: 2016/06/27
[11:14:13.730] INFO: *** Version: v1.9.0-814-g7497
[11:14:13.730] INFO: readRocDacs: data/myinq/dacParameters35_C0.dat .. data/myinq/dacParameters35_C15.dat
[11:14:13.731] INFO: readTbmDacs: data/myinq/tbmParameters_C0a.dat .. data/myinq/tbmParameters_C0b.dat
[11:14:13.731] INFO: readMaskFile: data/myinq/defaultMaskFile.dat
[11:14:13.731] INFO: readTrimFile: data/myinq/trimParameters35_C0.dat .. data/myinq/trimParameters35_C15.dat
[11:14:13.882] INFO: clk: 4
[11:14:13.882] INFO: ctr: 4
[11:14:13.882] INFO: sda: 19
[11:14:13.882] INFO: tin: 9
[11:14:13.882] INFO: level: 15
[11:14:13.882] INFO: triggerdelay: 0
[11:14:13.882] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[11:14:13.882] INFO: Log level: DEBUG
[11:14:13.893] QUIET: Connection to board DTB_WRMNMR opened.
[11:14:13.896] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[11:14:13.899] INFO: RPC call hashes of host and DTB match: 398089610
[11:14:15.503] INFO: DUT info:
[11:14:15.503] INFO: The DUT currently contains the following objects:
[11:14:15.503] INFO: 2 TBM Cores tbm08c (2 ON)
[11:14:15.503] INFO: TBM Core alpha (0): 7 registers set
[11:14:15.503] INFO: TBM Core beta (1): 7 registers set
[11:14:15.503] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:14:15.503] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.503] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.503] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.503] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.503] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.503] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.503] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.503] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.503] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.503] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.503] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.503] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.503] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.503] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.503] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.503] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:14:15.504] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:14:15.505] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:14:15.506] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:14:15.507] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:14:15.509] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31371264
[11:14:15.509] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1b340d0
[11:14:15.509] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x18d6360
[11:14:15.509] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f6f45d94010
[11:14:15.509] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f6f4bfff510
[11:14:15.509] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31379456 fPxarMemory = 0x7f6f45d94010
[11:14:15.510] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[11:14:15.512] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 475.1mA
[11:14:15.512] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[11:14:16.005] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1f1ed70
[11:14:16.066] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:14:16.067] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:14:16.067] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:14:16.072] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:14:16.072] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:14:16.072] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:14:16.082] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:14:16.083] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:14:16.109] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:14:16.109] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:14:16.119] INFO: PixTestCmd::init()
[11:14:16.126] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:14:16.126] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:14:16.126] INFO: readGainPedestalParameters data/myinq/phCalibrationFitErr35_C0.dat .. data/myinq/phCalibrationFitErr35_C15.dat
[11:14:16.369] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:14:16.369] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:14:16.384] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:14:16.389] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:14:16.389] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:14:16.390] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:14:16.410] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:14:16.410] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:14:16.410] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:14:16.411] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:14:16.411] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:14:16.418] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:14:16.418] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:14:16.439] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:14:16.439] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:14:16.439] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:14:16.439] INFO: readReadbackCal: data/myinq/readbackCal_C0.dat .. data/myinq/readbackCal_C15.dat
[11:14:16.450] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:14:16.459] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[11:14:16.459] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[11:14:16.459] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[11:14:16.459] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[11:14:16.478] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:14:16.479] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:14:20.201] INFO: ######################################################################
[11:14:20.201] INFO: PixTestAlive::doTest()
[11:14:20.201] INFO: ######################################################################
[11:14:20.204] INFO: ----------------------------------------------------------------------
[11:14:20.204] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:14:20.204] INFO: ----------------------------------------------------------------------
[11:14:20.207] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:14:20.586] INFO: Expecting 41600 events.
[11:14:24.788] INFO: 41600 events read in total (3483ms).
[11:14:24.936] INFO: Test took 4729ms.
[11:14:24.948] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:24.948] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[11:14:24.948] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[11:14:25.221] INFO: PixTestAlive::aliveTest() done
[11:14:25.221] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0
[11:14:25.221] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 11 27 57 95 104 91 100 105 99 124 88 99 93 57 34 4
[11:14:25.247] INFO: ----------------------------------------------------------------------
[11:14:25.247] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:14:25.247] INFO: ----------------------------------------------------------------------
[11:14:25.249] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:14:25.626] INFO: Expecting 41600 events.
[11:14:28.560] INFO: 41600 events read in total (2218ms).
[11:14:28.560] INFO: Test took 3311ms.
[11:14:28.560] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:28.560] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[11:14:28.560] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[11:14:28.561] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:14:28.983] INFO: PixTestAlive::maskTest() done
[11:14:28.983] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:14:29.005] INFO: ----------------------------------------------------------------------
[11:14:29.005] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:14:29.005] INFO: ----------------------------------------------------------------------
[11:14:29.007] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:14:29.386] INFO: Expecting 41600 events.
[11:14:33.581] INFO: 41600 events read in total (3480ms).
[11:14:33.583] INFO: Test took 4576ms.
[11:14:33.594] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:33.594] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[11:14:33.594] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[11:14:33.964] INFO: PixTestAlive::addressDecodingTest() done
[11:14:33.964] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:14:33.964] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[11:14:36.598] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:14:36.598] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C4_V0 -> PixelAlive_mod
[11:14:38.990] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[11:14:38.990] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[11:14:38.990] INFO: ----------------------------------------------------------------------
[11:14:38.990] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:14:38.990] INFO: ----------------------------------------------------------------------
[11:14:39.129] INFO: Expecting 768 events.
[11:14:40.258] INFO: 768 events read in total (414ms).
[11:14:40.258] INFO: Test took 1263ms.
[11:14:40.264] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:14:41.062] INFO: Expecting 41600 events.
[11:14:44.945] INFO: 41600 events read in total (3356ms).
[11:14:44.951] INFO: Test took 4687ms.
[11:14:45.126] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:45.126] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1015513
[11:14:45.126] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[11:14:45.127] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:14:45.200] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:14:45.866] INFO: Expecting 41600 events.
[11:14:49.832] INFO: 41600 events read in total (3440ms).
[11:14:49.840] INFO: Test took 4640ms.
[11:14:50.009] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:50.009] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1022530
[11:14:50.009] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[11:14:50.010] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:14:50.084] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:14:50.750] INFO: Expecting 41600 events.
[11:14:54.738] INFO: 41600 events read in total (3462ms).
[11:14:54.746] INFO: Test took 4661ms.
[11:14:54.917] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:54.917] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1024031
[11:14:54.917] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[11:14:54.917] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:14:54.992] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:14:55.658] INFO: Expecting 41600 events.
[11:14:59.672] INFO: 41600 events read in total (3487ms).
[11:14:59.680] INFO: Test took 4688ms.
[11:14:59.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:14:59.850] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1026870
[11:14:59.851] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[11:14:59.851] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:14:59.927] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:15:00.592] INFO: Expecting 41600 events.
[11:15:04.592] INFO: 41600 events read in total (3474ms).
[11:15:04.600] INFO: Test took 4673ms.
[11:15:04.768] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:04.768] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1023922
[11:15:04.768] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[11:15:04.769] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:15:04.844] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:15:05.509] INFO: Expecting 41600 events.
[11:15:09.544] INFO: 41600 events read in total (3508ms).
[11:15:09.552] INFO: Test took 4708ms.
[11:15:09.718] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:09.718] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1026099
[11:15:09.718] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[11:15:09.719] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:15:09.794] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:15:10.459] INFO: Expecting 41600 events.
[11:15:14.478] INFO: 41600 events read in total (3492ms).
[11:15:14.486] INFO: Test took 4691ms.
[11:15:14.653] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:14.653] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1027099
[11:15:14.653] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[11:15:14.654] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:15:14.729] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:15:15.396] INFO: Expecting 41600 events.
[11:15:19.431] INFO: 41600 events read in total (3509ms).
[11:15:19.439] INFO: Test took 4709ms.
[11:15:19.606] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:19.606] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1023709
[11:15:19.606] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[11:15:19.607] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:15:19.683] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:15:20.348] INFO: Expecting 41600 events.
[11:15:24.379] INFO: 41600 events read in total (3504ms).
[11:15:24.387] INFO: Test took 4704ms.
[11:15:24.555] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:24.555] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1025768
[11:15:24.555] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[11:15:24.556] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:15:24.631] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:15:25.296] INFO: Expecting 41600 events.
[11:15:29.341] INFO: 41600 events read in total (3518ms).
[11:15:29.349] INFO: Test took 4718ms.
[11:15:29.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:29.520] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1025454
[11:15:29.520] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[11:15:29.521] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:15:29.596] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:15:30.261] INFO: Expecting 41600 events.
[11:15:34.296] INFO: 41600 events read in total (3508ms).
[11:15:34.304] INFO: Test took 4708ms.
[11:15:34.472] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:34.472] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1024372
[11:15:34.472] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[11:15:34.472] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:15:34.548] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:15:35.215] INFO: Expecting 41600 events.
[11:15:39.262] INFO: 41600 events read in total (3521ms).
[11:15:39.270] INFO: Test took 4721ms.
[11:15:39.441] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:39.441] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1025904
[11:15:39.441] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[11:15:39.441] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:15:39.516] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:15:40.181] INFO: Expecting 41600 events.
[11:15:44.219] INFO: 41600 events read in total (3511ms).
[11:15:44.227] INFO: Test took 4710ms.
[11:15:44.491] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:44.491] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1026468
[11:15:44.491] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[11:15:44.492] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:15:44.567] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:15:45.232] INFO: Expecting 41600 events.
[11:15:49.262] INFO: 41600 events read in total (3503ms).
[11:15:49.270] INFO: Test took 4703ms.
[11:15:49.464] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:49.464] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1024838
[11:15:49.464] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[11:15:49.464] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:15:49.540] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:15:50.205] INFO: Expecting 41600 events.
[11:15:54.241] INFO: 41600 events read in total (3510ms).
[11:15:54.249] INFO: Test took 4709ms.
[11:15:54.418] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:54.418] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1025899
[11:15:54.418] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[11:15:54.419] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:15:54.494] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:15:55.159] INFO: Expecting 41600 events.
[11:15:59.184] INFO: 41600 events read in total (3498ms).
[11:15:59.192] INFO: Test took 4698ms.
[11:15:59.388] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:15:59.388] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1024880
[11:15:59.388] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[11:15:59.389] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:15:59.464] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:16:00.129] INFO: Expecting 41600 events.
[11:16:04.169] INFO: 41600 events read in total (3513ms).
[11:16:04.177] INFO: Test took 4713ms.
[11:16:04.347] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:04.347] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1025928
[11:16:04.347] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[11:16:04.348] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:16:04.423] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:16:05.088] INFO: Expecting 41600 events.
[11:16:09.103] INFO: 41600 events read in total (3488ms).
[11:16:09.111] INFO: Test took 4688ms.
[11:16:09.317] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:09.317] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1025419
[11:16:09.317] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[11:16:09.317] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:16:09.390] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:16:10.055] INFO: Expecting 41600 events.
[11:16:14.028] INFO: 41600 events read in total (3446ms).
[11:16:14.035] INFO: Test took 4645ms.
[11:16:14.207] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:14.207] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1019829
[11:16:14.207] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[11:16:14.207] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:16:14.282] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:16:14.947] INFO: Expecting 41600 events.
[11:16:18.741] INFO: 41600 events read in total (3267ms).
[11:16:18.747] INFO: Test took 4465ms.
[11:16:18.912] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:16:18.913] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1014551
[11:16:18.913] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[11:16:18.913] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:16:19.206] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 127 eff = 0.999519
[11:16:19.207] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 150 eff = 0.998966
[11:16:19.207] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 167 eff = 0.996058
[11:16:19.207] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 155 eff = 0.993125
[11:16:19.207] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 161 eff = 0.992043
[11:16:19.207] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 146 eff = 0.991418
[11:16:19.207] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 146 eff = 0.991827
[11:16:19.207] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 155 eff = 0.992428
[11:16:19.208] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 143 eff = 0.990889
[11:16:19.208] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 161 eff = 0.990841
[11:16:19.208] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 174 eff = 0.993221
[11:16:19.208] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 161 eff = 0.954471
[11:16:19.208] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 164 eff = 0.991394
[11:16:19.208] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 158 eff = 0.995889
[11:16:19.208] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 152 eff = 0.998558
[11:16:19.209] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 155 eff = 0.999519
[11:16:48.550] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[11:16:48.550] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[11:16:48.550] INFO: ----------------------------------------------------------------------
[11:16:48.550] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:16:48.550] INFO: ----------------------------------------------------------------------
[11:16:48.550] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[11:16:48.550] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[11:16:48.550] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[11:16:48.550] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[11:16:48.550] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[11:16:48.550] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[11:16:48.550] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[11:16:48.550] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[11:16:48.551] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[11:16:48.551] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[11:16:48.551] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[11:16:48.551] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[11:16:48.568] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:16:49.236] INFO: Expecting 208000 events.
[11:17:07.282] INFO: 208000 events read in total (17519ms).
[11:17:07.306] INFO: Test took 18738ms.
[11:17:08.256] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:17:08.256] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4799876
[11:17:08.256] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[11:17:08.256] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:17:08.803] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 148 0 1 0 0
[11:17:08.803] INFO: number of red-efficiency pixels: 91 213 616 1000 1296 1264 1315 1156 1327 1405 1225 1328 1217 610 230 32
[11:17:08.804] INFO: number of X-ray hits detected: 50275 134156 236933 366539 396847 395822 402846 370951 411808 411267 391381 364715 392343 244631 141282 21669
[11:17:08.804] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:17:08.804] INFO: number of Vcal hits detected: 207904 207772 207227 206779 206347 206299 206296 206557 206290 206165 206467 198489 206451 207214 207749 207967
[11:17:08.804] INFO: Vcal hit fiducial efficiency (%): 100.0 99.9 99.7 99.5 99.3 99.2 99.3 99.3 99.3 99.2 99.3 99.0 99.3 99.7 99.9 100.0
[11:17:08.804] INFO: Vcal hit overall efficiency (%): 100.0 99.9 99.6 99.4 99.2 99.2 99.2 99.3 99.2 99.1 99.3 95.4 99.3 99.6 99.9 100.0
[11:17:08.804] INFO: X-ray hit rate [MHz/cm2]: 14.7 39.3 69.4 107.4 116.3 116.0 118.1 108.7 120.7 120.5 114.7 106.9 115.0 71.7 41.4 6.4
[11:17:08.804] INFO: PixTestHighRate::doXPixelAlive() done
[11:17:08.849] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[11:17:08.849] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[11:17:08.849] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[11:17:08.849] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[11:17:08.849] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[11:17:08.849] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[11:17:08.849] INFO: PixTest:: pg_setup set to default.
[11:17:19.654] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:17:19.654] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[11:17:21.206] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:17:21.207] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 386.7mA
[11:17:21.207] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 477.5mA
[11:17:21.207] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[11:17:21.207] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:17:21.207] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:17:21.207] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:17:21.208] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:17:21.208] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:17:21.208] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:17:21.208] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:17:21.208] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:17:21.208] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:17:21.208] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:17:21.303] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:17:21.303] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:17:21.316] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:17:21.316] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:17:21.316] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:17:21.316] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[11:17:21.316] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:17:21.317] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:17:21.319] QUIET: Connection to board 43 closed.
[11:17:21.398] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master