Test Date: 1973-11-29 16:33
Analysis date: 2016-09-19 20:05
Logfile
hrData_40.log
[14:59:32.939] INFO: *** Welcome to pxar ***
[14:59:32.939] INFO: *** Today: 2016/09/16
[14:59:32.963] INFO: *** Version: v1.9.0-818-g96727
[14:59:32.964] INFO: readRocDacs: data/mqa40/dacParameters35_C0.dat .. data/mqa40/dacParameters35_C15.dat
[14:59:32.965] INFO: readTbmDacs: data/mqa40/tbmParameters_C0a.dat .. data/mqa40/tbmParameters_C0b.dat
[14:59:32.965] INFO: readMaskFile: data/mqa40/defaultMaskFile.dat
[14:59:32.965] INFO: readTrimFile: data/mqa40/trimParameters35_C0.dat .. data/mqa40/trimParameters35_C15.dat
[14:59:33.114] INFO: clk: 4
[14:59:33.114] INFO: ctr: 4
[14:59:33.114] INFO: sda: 19
[14:59:33.114] INFO: tin: 9
[14:59:33.114] INFO: level: 15
[14:59:33.114] INFO: triggerdelay: 0
[14:59:33.114] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[14:59:33.114] INFO: Log level: DEBUG
[14:59:33.126] QUIET: Connection to board DTB_WRPRHI opened.
[14:59:33.130] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[14:59:33.132] INFO: RPC call hashes of host and DTB match: 398089610
[14:59:34.737] INFO: DUT info:
[14:59:34.737] INFO: The DUT currently contains the following objects:
[14:59:34.737] INFO: 2 TBM Cores tbm08c (2 ON)
[14:59:34.737] INFO: TBM Core alpha (0): 7 registers set
[14:59:34.737] INFO: TBM Core beta (1): 7 registers set
[14:59:34.737] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:59:34.737] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.737] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.737] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.737] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.737] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.738] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.738] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.738] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.738] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.738] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.738] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.738] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.738] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.738] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.738] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.738] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:59:34.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:59:34.739] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:59:34.740] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:59:34.741] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:59:34.744] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30339072
[14:59:34.744] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x23750e0
[14:59:34.744] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2117370
[14:59:34.744] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7ff04c992010
[14:59:34.744] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7ff052bfd510
[14:59:34.744] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30347264 fPxarMemory = 0x7ff04c992010
[14:59:34.745] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[14:59:34.746] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[14:59:34.746] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[14:59:35.241] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2763f60
[14:59:35.307] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:59:35.307] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:59:35.307] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:59:35.313] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:59:35.313] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:59:35.313] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:59:35.325] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:59:35.325] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:59:35.344] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:59:35.344] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:59:35.361] INFO: PixTestCmd::init()
[14:59:35.372] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:59:35.372] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:59:35.372] INFO: readGainPedestalParameters data/mqa40/phCalibrationFitErr35_C0.dat .. data/mqa40/phCalibrationFitErr35_C15.dat
[14:59:35.624] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:59:35.624] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:59:35.638] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:59:35.644] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:59:35.644] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:59:35.645] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:59:35.666] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:59:35.666] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:59:35.666] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:59:35.666] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:59:35.666] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:59:35.673] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:59:35.673] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:59:35.690] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:59:35.690] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:59:35.690] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:59:35.690] INFO: readReadbackCal: data/mqa40/readbackCal_C0.dat .. data/mqa40/readbackCal_C15.dat
[14:59:35.700] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:59:35.708] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[14:59:35.708] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[14:59:35.708] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[14:59:35.708] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[14:59:35.725] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:59:35.725] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:59:40.101] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[14:59:42.045] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[14:59:42.045] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[14:59:42.045] INFO: ----------------------------------------------------------------------
[14:59:42.045] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[14:59:42.045] INFO: ----------------------------------------------------------------------
[14:59:43.019] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[14:59:55.498] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[14:59:55.501] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:00:22.699] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1229501 events.
[15:00:28.754] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1229501, pixels seen in all events: 5498270
[15:00:28.995] INFO: Resuming triggers.
[15:00:41.475] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[15:00:41.512] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:01:08.520] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1229413 events.
[15:01:14.578] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1229413, pixels seen in all events: 5497858
[15:01:14.812] INFO: Resuming triggers.
[15:01:27.292] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[15:01:27.330] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:01:54.482] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1229509 events.
[15:02:00.614] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1229509, pixels seen in all events: 5497063
[15:02:00.715] INFO: Resuming triggers.
[15:02:13.195] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[15:02:13.236] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:02:40.212] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1229486 events.
[15:02:46.270] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1229486, pixels seen in all events: 5497126
[15:02:46.508] INFO: Resuming triggers.
[15:02:56.665] INFO: data taking finished, elapsed time: 60 seconds.
[15:02:56.861] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:03:18.887] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1000599 events.
[15:03:23.847] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1000599, pixels seen in all events: 4475710
[15:03:23.995] INFO: PixTest:: pg_setup set to default.
[15:03:24.037] INFO: PixTestXray::doPhRun() done
[15:03:30.949] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:03:30.949] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 383.5mA
[15:03:30.950] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[15:03:30.950] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:03:30.950] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:03:30.950] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:03:30.950] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:03:30.950] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:03:30.950] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:03:30.951] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:03:30.951] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:03:30.951] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:03:30.951] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:03:30.951] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:03:30.951] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:03:30.951] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:03:30.951] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:03:30.951] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:03:30.951] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:03:30.951] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:03:30.951] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:03:30.951] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:03:31.226] QUIET: Connection to board 58 closed.
[15:03:31.305] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[15:03:41.510] INFO: *** Welcome to pxar ***
[15:03:41.510] INFO: *** Today: 2016/09/16
[15:03:41.535] INFO: *** Version: v1.9.0-818-g96727
[15:03:41.535] INFO: readRocDacs: data/mqa40/dacParameters35_C0.dat .. data/mqa40/dacParameters35_C15.dat
[15:03:41.536] INFO: readTbmDacs: data/mqa40/tbmParameters_C0a.dat .. data/mqa40/tbmParameters_C0b.dat
[15:03:41.536] INFO: readMaskFile: data/mqa40/defaultMaskFile.dat
[15:03:41.536] INFO: readTrimFile: data/mqa40/trimParameters35_C0.dat .. data/mqa40/trimParameters35_C15.dat
[15:03:41.688] INFO: clk: 4
[15:03:41.688] INFO: ctr: 4
[15:03:41.688] INFO: sda: 19
[15:03:41.688] INFO: tin: 9
[15:03:41.688] INFO: level: 15
[15:03:41.688] INFO: triggerdelay: 0
[15:03:41.688] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:03:41.688] INFO: Log level: DEBUG
[15:03:41.699] QUIET: Connection to board DTB_WRPRHI opened.
[15:03:41.702] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:03:41.704] INFO: RPC call hashes of host and DTB match: 398089610
[15:03:43.306] INFO: DUT info:
[15:03:43.306] INFO: The DUT currently contains the following objects:
[15:03:43.306] INFO: 2 TBM Cores tbm08c (2 ON)
[15:03:43.306] INFO: TBM Core alpha (0): 7 registers set
[15:03:43.306] INFO: TBM Core beta (1): 7 registers set
[15:03:43.307] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:03:43.307] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:03:43.307] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:03:43.307] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:03:43.307] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:03:43.307] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:03:43.307] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:03:43.307] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:03:43.307] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:03:43.308] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:03:43.309] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:03:43.310] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:03:43.313] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30330880
[15:03:43.313] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x16870e0
[15:03:43.313] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1429370
[15:03:43.313] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f6ae9d94010
[15:03:43.313] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f6aeffff510
[15:03:43.313] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30339072 fPxarMemory = 0x7f6ae9d94010
[15:03:43.314] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[15:03:43.315] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[15:03:43.315] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:03:43.817] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1a75fd0
[15:03:43.880] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:03:43.880] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:03:43.880] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:03:43.886] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:03:43.886] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:03:43.886] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:03:43.895] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:03:43.895] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:03:43.904] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:03:43.904] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:03:43.919] INFO: PixTestCmd::init()
[15:03:43.927] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:03:43.927] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:03:43.927] INFO: readGainPedestalParameters data/mqa40/phCalibrationFitErr35_C0.dat .. data/mqa40/phCalibrationFitErr35_C15.dat
[15:03:44.178] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:03:44.178] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:03:44.193] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:03:44.199] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:03:44.199] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:03:44.199] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:03:44.221] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:03:44.221] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:03:44.221] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:03:44.221] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:03:44.221] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:03:44.230] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:03:44.230] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:03:44.253] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:03:44.253] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:03:44.253] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:03:44.253] INFO: readReadbackCal: data/mqa40/readbackCal_C0.dat .. data/mqa40/readbackCal_C15.dat
[15:03:44.265] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:03:44.279] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:03:44.279] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:03:44.280] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:03:44.280] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:03:44.296] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:03:44.296] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:03:58.733] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[15:03:59.597] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[15:03:59.597] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[15:03:59.597] INFO: ----------------------------------------------------------------------
[15:03:59.597] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[15:03:59.597] INFO: ----------------------------------------------------------------------
[15:04:00.570] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[15:04:08.406] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:04:08.410] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:04:33.597] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 772057 events.
[15:04:46.167] DEBUG: <PixTestXray.cc/processData:L823> # events read: 772057, pixels seen in all events: 10989131
[15:04:46.431] INFO: Resuming triggers.
[15:04:54.269] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[15:04:54.299] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:05:19.450] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 772078 events.
[15:05:32.067] DEBUG: <PixTestXray.cc/processData:L823> # events read: 772078, pixels seen in all events: 10987345
[15:05:32.271] INFO: Resuming triggers.
[15:05:40.108] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:05:40.181] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:06:05.316] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 772118 events.
[15:06:17.899] DEBUG: <PixTestXray.cc/processData:L823> # events read: 772118, pixels seen in all events: 10986742
[15:06:18.105] INFO: Resuming triggers.
[15:06:25.942] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[15:06:26.018] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:06:51.162] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 772279 events.
[15:07:03.739] DEBUG: <PixTestXray.cc/processData:L823> # events read: 772279, pixels seen in all events: 10989810
[15:07:03.939] INFO: Resuming triggers.
[15:07:11.778] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[15:07:11.857] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:07:36.958] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 772216 events.
[15:07:49.549] DEBUG: <PixTestXray.cc/processData:L823> # events read: 772216, pixels seen in all events: 10985863
[15:07:49.792] INFO: Resuming triggers.
[15:07:57.632] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:07:57.671] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:08:22.790] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 772324 events.
[15:08:35.374] DEBUG: <PixTestXray.cc/processData:L823> # events read: 772324, pixels seen in all events: 10983984
[15:08:35.618] INFO: Resuming triggers.
[15:08:43.457] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[15:08:43.495] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:09:08.464] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 772277 events.
[15:09:21.044] DEBUG: <PixTestXray.cc/processData:L823> # events read: 772277, pixels seen in all events: 10984831
[15:09:21.288] INFO: Resuming triggers.
[15:09:26.498] INFO: data taking finished, elapsed time: 60 seconds.
[15:09:26.694] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:09:43.471] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 513204 events.
[15:09:51.824] DEBUG: <PixTestXray.cc/processData:L823> # events read: 513204, pixels seen in all events: 7303873
[15:09:51.952] INFO: PixTest:: pg_setup set to default.
[15:09:51.996] INFO: PixTestXray::doPhRun() done
[15:10:04.445] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:10:04.446] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 383.5mA
[15:10:04.446] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 469.5mA
[15:10:04.446] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[15:10:04.446] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:10:04.447] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:10:04.447] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:10:04.447] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:10:04.447] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:10:04.447] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:10:04.447] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:10:04.447] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:10:04.447] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:10:04.447] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:10:04.447] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:10:04.447] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:10:04.447] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:10:04.447] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:10:04.448] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:10:04.448] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:10:04.448] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:10:04.448] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:10:04.757] QUIET: Connection to board 58 closed.
[15:10:04.837] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[14:53:35.670] INFO: *** Welcome to pxar ***
[14:53:35.670] INFO: *** Today: 2016/09/16
[14:53:35.694] INFO: *** Version: v1.9.0-818-g96727
[14:53:35.694] INFO: readRocDacs: data/mqa40/dacParameters35_C0.dat .. data/mqa40/dacParameters35_C15.dat
[14:53:35.695] INFO: readTbmDacs: data/mqa40/tbmParameters_C0a.dat .. data/mqa40/tbmParameters_C0b.dat
[14:53:35.695] INFO: readMaskFile: data/mqa40/defaultMaskFile.dat
[14:53:35.695] INFO: readTrimFile: data/mqa40/trimParameters35_C0.dat .. data/mqa40/trimParameters35_C15.dat
[14:53:35.845] INFO: clk: 4
[14:53:35.845] INFO: ctr: 4
[14:53:35.845] INFO: sda: 19
[14:53:35.845] INFO: tin: 9
[14:53:35.845] INFO: level: 15
[14:53:35.845] INFO: triggerdelay: 0
[14:53:35.845] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[14:53:35.845] INFO: Log level: DEBUG
[14:53:35.856] QUIET: Connection to board DTB_WRPRHI opened.
[14:53:35.859] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[14:53:35.862] INFO: RPC call hashes of host and DTB match: 398089610
[14:53:37.462] INFO: DUT info:
[14:53:37.462] INFO: The DUT currently contains the following objects:
[14:53:37.462] INFO: 2 TBM Cores tbm08c (2 ON)
[14:53:37.462] INFO: TBM Core alpha (0): 7 registers set
[14:53:37.462] INFO: TBM Core beta (1): 7 registers set
[14:53:37.462] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:53:37.462] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.462] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.462] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.462] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.462] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.462] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.462] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.462] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.463] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.463] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.463] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.463] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.463] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.463] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.463] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.463] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:53:37.463] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:53:37.464] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:53:37.465] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:53:37.466] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:53:37.468] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30334976
[14:53:37.468] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xc8c0e0
[14:53:37.468] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xa2e370
[14:53:37.468] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f7b69d94010
[14:53:37.468] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f7b6ffff510
[14:53:37.468] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30343168 fPxarMemory = 0x7f7b69d94010
[14:53:37.470] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[14:53:37.471] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[14:53:37.471] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[14:53:37.974] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x107b0a0
[14:53:38.038] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:53:38.038] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:53:38.038] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:53:38.044] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:53:38.044] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:53:38.044] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:53:38.055] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:53:38.055] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:53:38.080] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:53:38.080] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:53:38.093] INFO: PixTestCmd::init()
[14:53:38.107] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:53:38.107] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:53:38.107] INFO: readGainPedestalParameters data/mqa40/phCalibrationFitErr35_C0.dat .. data/mqa40/phCalibrationFitErr35_C15.dat
[14:53:38.355] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:53:38.355] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:53:38.369] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:53:38.375] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:53:38.375] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:53:38.375] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:53:38.395] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:53:38.395] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:53:38.395] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:53:38.395] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:53:38.395] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:53:38.401] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:53:38.401] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:53:38.416] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:53:38.416] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:53:38.416] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:53:38.416] INFO: readReadbackCal: data/mqa40/readbackCal_C0.dat .. data/mqa40/readbackCal_C15.dat
[14:53:38.429] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:53:38.437] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[14:53:38.437] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[14:53:38.437] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[14:53:38.437] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[14:53:38.457] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:53:38.457] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:53:46.384] INFO: ######################################################################
[14:53:46.384] INFO: PixTestAlive::doTest()
[14:53:46.384] INFO: ######################################################################
[14:53:46.387] INFO: ----------------------------------------------------------------------
[14:53:46.387] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:53:46.387] INFO: ----------------------------------------------------------------------
[14:53:46.390] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:46.768] INFO: Expecting 41600 events.
[14:53:51.157] INFO: 41600 events read in total (3671ms).
[14:53:51.303] INFO: Test took 4913ms.
[14:53:51.315] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:51.315] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:53:51.315] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:53:51.591] INFO: PixTestAlive::aliveTest() done
[14:53:51.591] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:53:51.591] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 15 14 26 31 40 49 38 32 44 53 45 44 37 26 12 22
[14:53:51.618] INFO: ----------------------------------------------------------------------
[14:53:51.618] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:53:51.618] INFO: ----------------------------------------------------------------------
[14:53:51.621] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:51.997] INFO: Expecting 41600 events.
[14:53:55.119] INFO: 41600 events read in total (2406ms).
[14:53:55.120] INFO: Test took 3499ms.
[14:53:55.120] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:55.120] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[14:53:55.120] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[14:53:55.120] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:53:55.542] INFO: PixTestAlive::maskTest() done
[14:53:55.542] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:53:55.565] INFO: ----------------------------------------------------------------------
[14:53:55.565] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:53:55.565] INFO: ----------------------------------------------------------------------
[14:53:55.567] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:55.944] INFO: Expecting 41600 events.
[14:54:00.296] INFO: 41600 events read in total (3637ms).
[14:54:00.297] INFO: Test took 4730ms.
[14:54:00.309] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:00.309] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:54:00.309] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[14:54:00.677] INFO: PixTestAlive::addressDecodingTest() done
[14:54:00.677] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:54:00.678] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[14:54:06.365] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:54:06.365] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[14:54:09.300] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[14:54:09.300] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:54:09.300] INFO: ----------------------------------------------------------------------
[14:54:09.301] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:54:09.301] INFO: ----------------------------------------------------------------------
[14:54:09.440] INFO: Expecting 768 events.
[14:54:10.574] INFO: 768 events read in total (419ms).
[14:54:10.574] INFO: Test took 1268ms.
[14:54:10.580] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:11.378] INFO: Expecting 41600 events.
[14:54:14.706] INFO: 41600 events read in total (2802ms).
[14:54:14.710] INFO: Test took 4130ms.
[14:54:14.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:14.786] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 455275
[14:54:14.786] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:54:14.787] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:14.823] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:15.489] INFO: Expecting 41600 events.
[14:54:18.926] INFO: 41600 events read in total (2910ms).
[14:54:18.930] INFO: Test took 4106ms.
[14:54:19.007] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:19.007] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 466578
[14:54:19.007] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:54:19.008] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:19.046] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:19.711] INFO: Expecting 41600 events.
[14:54:23.217] INFO: 41600 events read in total (2979ms).
[14:54:23.220] INFO: Test took 4174ms.
[14:54:23.299] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:23.299] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469387
[14:54:23.299] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:54:23.299] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:23.340] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:24.007] INFO: Expecting 41600 events.
[14:54:27.514] INFO: 41600 events read in total (2981ms).
[14:54:27.517] INFO: Test took 4177ms.
[14:54:27.596] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:27.596] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 467811
[14:54:27.596] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:54:27.596] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:27.636] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:28.303] INFO: Expecting 41600 events.
[14:54:31.825] INFO: 41600 events read in total (2996ms).
[14:54:31.829] INFO: Test took 4193ms.
[14:54:31.907] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:31.907] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469538
[14:54:31.907] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:54:31.907] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:31.947] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:32.612] INFO: Expecting 41600 events.
[14:54:36.125] INFO: 41600 events read in total (2987ms).
[14:54:36.128] INFO: Test took 4181ms.
[14:54:36.207] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:36.207] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 468276
[14:54:36.207] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:54:36.207] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:36.247] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:36.914] INFO: Expecting 41600 events.
[14:54:40.438] INFO: 41600 events read in total (2998ms).
[14:54:40.441] INFO: Test took 4194ms.
[14:54:40.519] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:40.519] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 468328
[14:54:40.519] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:54:40.520] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:40.560] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:41.226] INFO: Expecting 41600 events.
[14:54:44.758] INFO: 41600 events read in total (3005ms).
[14:54:44.762] INFO: Test took 4202ms.
[14:54:44.839] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:44.839] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 468661
[14:54:44.839] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:54:44.839] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:44.878] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:45.543] INFO: Expecting 41600 events.
[14:54:49.059] INFO: 41600 events read in total (2989ms).
[14:54:49.062] INFO: Test took 4184ms.
[14:54:49.140] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:49.140] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 468404
[14:54:49.140] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:54:49.140] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:49.180] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:49.845] INFO: Expecting 41600 events.
[14:54:53.385] INFO: 41600 events read in total (3013ms).
[14:54:53.389] INFO: Test took 4209ms.
[14:54:53.466] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:53.466] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469267
[14:54:53.466] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:54:53.467] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:53.505] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:54.171] INFO: Expecting 41600 events.
[14:54:57.704] INFO: 41600 events read in total (3007ms).
[14:54:57.707] INFO: Test took 4201ms.
[14:54:57.785] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:57.785] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469173
[14:54:57.785] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:54:57.785] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:57.824] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:58.489] INFO: Expecting 41600 events.
[14:55:02.000] INFO: 41600 events read in total (2984ms).
[14:55:02.004] INFO: Test took 4180ms.
[14:55:02.081] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:02.081] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 468141
[14:55:02.081] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:55:02.081] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:02.120] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:02.785] INFO: Expecting 41600 events.
[14:55:06.305] INFO: 41600 events read in total (2993ms).
[14:55:06.309] INFO: Test took 4189ms.
[14:55:06.385] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:06.385] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469180
[14:55:06.385] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:55:06.386] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:06.425] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:07.090] INFO: Expecting 41600 events.
[14:55:10.593] INFO: 41600 events read in total (2976ms).
[14:55:10.597] INFO: Test took 4172ms.
[14:55:10.674] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:10.674] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469247
[14:55:10.674] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:55:10.674] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:10.713] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:11.379] INFO: Expecting 41600 events.
[14:55:14.897] INFO: 41600 events read in total (2992ms).
[14:55:14.901] INFO: Test took 4187ms.
[14:55:14.977] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:14.977] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 468086
[14:55:14.977] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:55:14.978] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:15.017] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:15.682] INFO: Expecting 41600 events.
[14:55:19.211] INFO: 41600 events read in total (3002ms).
[14:55:19.214] INFO: Test took 4197ms.
[14:55:19.290] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:19.290] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 467513
[14:55:19.290] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:55:19.290] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:19.330] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:19.996] INFO: Expecting 41600 events.
[14:55:23.524] INFO: 41600 events read in total (3002ms).
[14:55:23.528] INFO: Test took 4198ms.
[14:55:23.604] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:23.604] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 468146
[14:55:23.604] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:55:23.605] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:23.644] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:24.310] INFO: Expecting 41600 events.
[14:55:27.824] INFO: 41600 events read in total (2988ms).
[14:55:27.828] INFO: Test took 4184ms.
[14:55:27.909] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:27.909] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 468174
[14:55:27.909] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:55:27.910] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:27.949] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:28.616] INFO: Expecting 41600 events.
[14:55:32.107] INFO: 41600 events read in total (2965ms).
[14:55:32.111] INFO: Test took 4162ms.
[14:55:32.187] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:32.187] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 468002
[14:55:32.187] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:55:32.187] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:32.227] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:32.892] INFO: Expecting 41600 events.
[14:55:36.283] INFO: 41600 events read in total (2864ms).
[14:55:36.286] INFO: Test took 4059ms.
[14:55:36.362] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:36.362] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 462526
[14:55:36.362] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:55:36.362] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:36.622] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 132 eff = 0.999519
[14:55:36.622] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 140 eff = 0.99976
[14:55:36.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 145 eff = 0.999543
[14:55:36.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 174 eff = 0.999207
[14:55:36.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 176 eff = 0.998846
[14:55:36.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 164 eff = 0.99899
[14:55:36.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 150 eff = 0.999231
[14:55:36.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 158 eff = 0.999063
[14:55:36.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 145 eff = 0.999063
[14:55:36.624] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 151 eff = 0.998846
[14:55:36.624] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 171 eff = 0.999014
[14:55:36.624] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 170 eff = 0.999014
[14:55:36.624] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 167 eff = 0.999014
[14:55:36.624] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 165 eff = 0.999447
[14:55:36.624] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 135 eff = 0.999591
[14:55:36.624] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 134 eff = 0.999688
[14:55:41.836] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[14:55:41.836] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:55:41.837] INFO: ----------------------------------------------------------------------
[14:55:41.837] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:55:41.837] INFO: ----------------------------------------------------------------------
[14:55:41.837] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:55:41.837] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:55:41.837] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:55:41.837] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:55:41.837] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:55:41.837] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:55:41.837] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:55:41.837] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:55:41.837] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:55:41.837] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:55:41.837] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:55:41.837] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:55:41.857] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:55:42.526] INFO: Expecting 208000 events.
[14:55:55.915] INFO: 208000 events read in total (12863ms).
[14:55:55.927] INFO: Test took 14070ms.
[14:55:56.327] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:56.328] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2062006
[14:55:56.328] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:55:56.328] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:55:56.699] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:55:56.699] INFO: number of red-efficiency pixels: 109 78 155 223 252 258 266 192 236 258 215 275 247 167 87 117
[14:55:56.699] INFO: number of X-ray hits detected: 85797 59593 100216 150846 154663 158570 148925 135599 143119 158915 157617 151784 148689 100823 61245 79045
[14:55:56.699] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:55:56.699] INFO: number of Vcal hits detected: 207888 207920 207841 207772 207737 207733 207730 207800 207757 207733 207779 207710 207684 207827 207911 207882
[14:55:56.699] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[14:55:56.699] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 100.0 99.9
[14:55:56.699] INFO: X-ray hit rate [MHz/cm2]: 25.1 17.5 29.4 44.2 45.3 46.5 43.7 39.7 41.9 46.6 46.2 44.5 43.6 29.6 18.0 23.2
[14:55:56.699] INFO: PixTestHighRate::doXPixelAlive() done
[14:55:56.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:55:56.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:55:56.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:55:56.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:55:56.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:55:56.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:55:56.744] INFO: PixTest:: pg_setup set to default.
[14:56:01.444] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:56:01.445] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[14:56:02.844] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[14:56:02.844] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[14:56:02.845] INFO: ----------------------------------------------------------------------
[14:56:02.845] INFO: PixTestHighRate::runDaq() running for 2 seconds
[14:56:02.845] INFO: ----------------------------------------------------------------------
[14:56:03.046] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[14:56:03.046] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[14:56:03.046] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[14:56:03.046] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[14:56:03.046] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[14:56:03.046] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[14:56:03.046] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[14:56:03.046] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[14:56:03.046] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[14:56:03.046] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[14:56:03.046] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[14:56:03.046] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[14:56:04.003] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[14:56:06.006] INFO: data taking finished, elapsed time: 2 seconds.
[14:56:07.496] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39822 events with 378207 pixels
[14:56:07.502] INFO: PixTest:: pg_setup set to default.
[14:56:07.641] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[14:56:07.641] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[14:56:07.642] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[14:56:07.642] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[14:56:07.642] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[14:56:07.642] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[14:56:07.642] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[14:56:07.642] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[14:56:07.642] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[14:56:07.643] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[14:56:07.643] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[14:56:07.643] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[14:56:07.643] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[14:56:07.643] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[14:56:07.643] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[14:56:07.643] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[14:56:07.644] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[14:56:07.644] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[14:56:07.644] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[14:56:07.644] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[14:56:07.644] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[14:56:07.644] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[14:56:07.644] INFO: Pixels without X-ray hits (per ROC): 731 1305 754 230 211 231 254 227 211 218 205 330 218 756 1326 845
[14:56:07.644] INFO: PixTestHighRate::doRunDaq() done
[14:56:30.140] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:56:30.140] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C13_V0 -> hitMap_daqbbtest_mod
[14:56:34.109] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[14:56:34.109] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 383.5mA
[14:56:34.110] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[14:56:34.110] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[14:56:34.110] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[14:56:34.110] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[14:56:34.110] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[14:56:34.110] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[14:56:34.111] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[14:56:34.111] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[14:56:34.111] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[14:56:34.111] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[14:56:34.111] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[14:56:34.111] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:56:34.214] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[14:56:34.214] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:56:34.227] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[14:56:34.227] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[14:56:34.227] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[14:56:34.227] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[14:56:34.227] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[14:56:34.227] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:56:34.230] QUIET: Connection to board 58 closed.
[14:56:34.310] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[14:49:58.794] INFO: *** Welcome to pxar ***
[14:49:58.794] INFO: *** Today: 2016/09/16
[14:49:58.818] INFO: *** Version: v1.9.0-818-g96727
[14:49:58.818] INFO: readRocDacs: data/mqa40/dacParameters35_C0.dat .. data/mqa40/dacParameters35_C15.dat
[14:49:58.819] INFO: readTbmDacs: data/mqa40/tbmParameters_C0a.dat .. data/mqa40/tbmParameters_C0b.dat
[14:49:58.819] INFO: readMaskFile: data/mqa40/defaultMaskFile.dat
[14:49:58.819] INFO: readTrimFile: data/mqa40/trimParameters35_C0.dat .. data/mqa40/trimParameters35_C15.dat
[14:49:58.971] INFO: clk: 4
[14:49:58.971] INFO: ctr: 4
[14:49:58.971] INFO: sda: 19
[14:49:58.971] INFO: tin: 9
[14:49:58.971] INFO: level: 15
[14:49:58.971] INFO: triggerdelay: 0
[14:49:58.971] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[14:49:58.971] INFO: Log level: DEBUG
[14:49:58.982] QUIET: Connection to board DTB_WRPRHI opened.
[14:49:58.986] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[14:49:58.988] INFO: RPC call hashes of host and DTB match: 398089610
[14:50:00.592] INFO: DUT info:
[14:50:00.592] INFO: The DUT currently contains the following objects:
[14:50:00.592] INFO: 2 TBM Cores tbm08c (2 ON)
[14:50:00.592] INFO: TBM Core alpha (0): 7 registers set
[14:50:00.592] INFO: TBM Core beta (1): 7 registers set
[14:50:00.592] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:50:00.592] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.592] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.592] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.592] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.592] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.593] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.593] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.593] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.593] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.593] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.593] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.593] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.593] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.593] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.593] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.593] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:50:00.593] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:50:00.594] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:50:00.595] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:50:00.596] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:50:00.598] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30339072
[14:50:00.598] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x25f90e0
[14:50:00.598] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x239b370
[14:50:00.598] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f3c05d94010
[14:50:00.598] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f3c0bfff510
[14:50:00.599] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30347264 fPxarMemory = 0x7f3c05d94010
[14:50:00.600] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[14:50:00.601] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[14:50:00.601] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[14:50:01.098] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x29e7fc0
[14:50:01.160] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:50:01.160] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:50:01.161] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:50:01.166] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:50:01.166] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:50:01.166] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:50:01.179] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:50:01.179] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:50:01.197] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:50:01.197] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:50:01.208] INFO: PixTestCmd::init()
[14:50:01.218] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:50:01.218] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:50:01.218] INFO: readGainPedestalParameters data/mqa40/phCalibrationFitErr35_C0.dat .. data/mqa40/phCalibrationFitErr35_C15.dat
[14:50:01.465] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:50:01.465] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:50:01.478] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:50:01.485] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:50:01.485] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:50:01.485] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:50:01.506] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:50:01.506] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:50:01.506] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:50:01.506] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:50:01.506] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:50:01.513] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:50:01.513] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:50:01.530] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:50:01.530] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:50:01.530] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:50:01.530] INFO: readReadbackCal: data/mqa40/readbackCal_C0.dat .. data/mqa40/readbackCal_C15.dat
[14:50:01.541] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:50:01.551] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[14:50:01.551] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[14:50:01.551] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[14:50:01.551] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[14:50:01.568] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:50:01.569] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:50:11.464] INFO: ######################################################################
[14:50:11.464] INFO: PixTestAlive::doTest()
[14:50:11.464] INFO: ######################################################################
[14:50:11.467] INFO: ----------------------------------------------------------------------
[14:50:11.467] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:50:11.467] INFO: ----------------------------------------------------------------------
[14:50:11.469] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:11.848] INFO: Expecting 41600 events.
[14:50:16.249] INFO: 41600 events read in total (3683ms).
[14:50:16.397] INFO: Test took 4928ms.
[14:50:16.409] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:16.409] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:50:16.409] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:50:16.684] INFO: PixTestAlive::aliveTest() done
[14:50:16.684] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:50:16.685] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 30 28 38 49 64 75 44 69 59 55 53 59 42 45 29 26
[14:50:16.714] INFO: ----------------------------------------------------------------------
[14:50:16.714] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:50:16.714] INFO: ----------------------------------------------------------------------
[14:50:16.716] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:17.094] INFO: Expecting 41600 events.
[14:50:20.223] INFO: 41600 events read in total (2414ms).
[14:50:20.224] INFO: Test took 3508ms.
[14:50:20.224] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:20.224] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[14:50:20.224] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[14:50:20.225] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:50:20.647] INFO: PixTestAlive::maskTest() done
[14:50:20.647] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:50:20.669] INFO: ----------------------------------------------------------------------
[14:50:20.669] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:50:20.669] INFO: ----------------------------------------------------------------------
[14:50:20.671] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:21.047] INFO: Expecting 41600 events.
[14:50:25.441] INFO: 41600 events read in total (3678ms).
[14:50:25.442] INFO: Test took 4771ms.
[14:50:25.454] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:25.454] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:50:25.454] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[14:50:25.824] INFO: PixTestAlive::addressDecodingTest() done
[14:50:25.824] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:50:25.825] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[14:50:41.964] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:50:41.964] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[14:50:44.700] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[14:50:44.700] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:50:44.700] INFO: ----------------------------------------------------------------------
[14:50:44.700] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:50:44.700] INFO: ----------------------------------------------------------------------
[14:50:44.840] INFO: Expecting 768 events.
[14:50:45.974] INFO: 768 events read in total (419ms).
[14:50:45.974] INFO: Test took 1268ms.
[14:50:45.980] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:46.777] INFO: Expecting 41600 events.
[14:50:50.337] INFO: 41600 events read in total (3033ms).
[14:50:50.341] INFO: Test took 4361ms.
[14:50:50.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:50.458] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 659187
[14:50:50.458] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:50:50.459] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:50:50.509] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:51.176] INFO: Expecting 41600 events.
[14:50:54.859] INFO: 41600 events read in total (3156ms).
[14:50:54.864] INFO: Test took 4355ms.
[14:50:54.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:54.979] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 669173
[14:50:54.979] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:50:54.980] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:50:55.032] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:55.697] INFO: Expecting 41600 events.
[14:50:59.403] INFO: 41600 events read in total (3180ms).
[14:50:59.408] INFO: Test took 4376ms.
[14:50:59.519] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:59.519] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 670533
[14:50:59.519] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:50:59.520] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:50:59.572] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:00.238] INFO: Expecting 41600 events.
[14:51:03.940] INFO: 41600 events read in total (3176ms).
[14:51:03.945] INFO: Test took 4373ms.
[14:51:04.059] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:04.059] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 670754
[14:51:04.059] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:51:04.060] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:04.112] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:04.778] INFO: Expecting 41600 events.
[14:51:08.526] INFO: 41600 events read in total (3222ms).
[14:51:08.530] INFO: Test took 4418ms.
[14:51:08.644] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:08.645] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 673179
[14:51:08.645] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:51:08.645] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:08.698] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:09.365] INFO: Expecting 41600 events.
[14:51:13.094] INFO: 41600 events read in total (3203ms).
[14:51:13.099] INFO: Test took 4401ms.
[14:51:13.213] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:13.213] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 672548
[14:51:13.213] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:51:13.214] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:13.267] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:13.932] INFO: Expecting 41600 events.
[14:51:17.643] INFO: 41600 events read in total (3184ms).
[14:51:17.647] INFO: Test took 4380ms.
[14:51:17.760] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:17.760] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 670620
[14:51:17.760] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:51:17.760] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:17.814] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:18.479] INFO: Expecting 41600 events.
[14:51:22.230] INFO: 41600 events read in total (3225ms).
[14:51:22.235] INFO: Test took 4421ms.
[14:51:22.352] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:22.352] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 672167
[14:51:22.352] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:51:22.352] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:22.405] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:23.070] INFO: Expecting 41600 events.
[14:51:26.835] INFO: 41600 events read in total (3238ms).
[14:51:26.839] INFO: Test took 4434ms.
[14:51:26.953] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:26.953] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 671353
[14:51:26.953] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:51:26.953] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:27.006] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:27.671] INFO: Expecting 41600 events.
[14:51:31.411] INFO: 41600 events read in total (3213ms).
[14:51:31.415] INFO: Test took 4409ms.
[14:51:31.528] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:31.528] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 671715
[14:51:31.528] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:51:31.528] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:31.581] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:32.247] INFO: Expecting 41600 events.
[14:51:36.005] INFO: 41600 events read in total (3231ms).
[14:51:36.010] INFO: Test took 4429ms.
[14:51:36.123] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:36.123] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 671168
[14:51:36.123] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:51:36.123] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:36.176] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:36.841] INFO: Expecting 41600 events.
[14:51:40.604] INFO: 41600 events read in total (3236ms).
[14:51:40.608] INFO: Test took 4432ms.
[14:51:40.721] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:40.721] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 672227
[14:51:40.721] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:51:40.722] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:40.775] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:41.440] INFO: Expecting 41600 events.
[14:51:45.199] INFO: 41600 events read in total (3232ms).
[14:51:45.203] INFO: Test took 4428ms.
[14:51:45.314] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:45.314] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 671211
[14:51:45.314] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:51:45.315] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:45.368] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:46.033] INFO: Expecting 41600 events.
[14:51:49.803] INFO: 41600 events read in total (3243ms).
[14:51:49.807] INFO: Test took 4439ms.
[14:51:49.920] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:49.920] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 673100
[14:51:49.920] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:51:49.921] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:49.974] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:50.640] INFO: Expecting 41600 events.
[14:51:54.401] INFO: 41600 events read in total (3234ms).
[14:51:54.405] INFO: Test took 4431ms.
[14:51:54.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:54.520] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 671810
[14:51:54.520] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:51:54.520] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:54.574] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:55.240] INFO: Expecting 41600 events.
[14:51:59.003] INFO: 41600 events read in total (3238ms).
[14:51:59.008] INFO: Test took 4434ms.
[14:51:59.161] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:59.161] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 670926
[14:51:59.161] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:51:59.162] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:59.215] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:59.880] INFO: Expecting 41600 events.
[14:52:03.630] INFO: 41600 events read in total (3223ms).
[14:52:03.635] INFO: Test took 4420ms.
[14:52:03.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:03.748] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 670770
[14:52:03.748] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:52:03.749] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:03.803] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:52:04.469] INFO: Expecting 41600 events.
[14:52:08.210] INFO: 41600 events read in total (3215ms).
[14:52:08.215] INFO: Test took 4412ms.
[14:52:08.328] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:08.328] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 670125
[14:52:08.328] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:52:08.329] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:08.382] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:52:09.048] INFO: Expecting 41600 events.
[14:52:12.785] INFO: 41600 events read in total (3211ms).
[14:52:12.789] INFO: Test took 4407ms.
[14:52:12.902] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:12.902] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 671760
[14:52:12.902] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:52:12.903] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:12.956] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:52:13.622] INFO: Expecting 41600 events.
[14:52:17.241] INFO: 41600 events read in total (3092ms).
[14:52:17.245] INFO: Test took 4289ms.
[14:52:17.356] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:17.356] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 666307
[14:52:17.356] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:52:17.356] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:17.630] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 135 eff = 0.999183
[14:52:17.630] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 138 eff = 0.999495
[14:52:17.630] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 148 eff = 0.998774
[14:52:17.631] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 175 eff = 0.998005
[14:52:17.631] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 180 eff = 0.997572
[14:52:17.631] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 164 eff = 0.99738
[14:52:17.631] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 162 eff = 0.998221
[14:52:17.631] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 157 eff = 0.997957
[14:52:17.631] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 163 eff = 0.997909
[14:52:17.631] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 153 eff = 0.997284
[14:52:17.632] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 168 eff = 0.997788
[14:52:17.632] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 175 eff = 0.997644
[14:52:17.632] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 164 eff = 0.998173
[14:52:17.632] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 175 eff = 0.99875
[14:52:17.632] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 134 eff = 0.999447
[14:52:17.632] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 134 eff = 0.999303
[14:52:33.636] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[14:52:33.636] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:52:33.636] INFO: ----------------------------------------------------------------------
[14:52:33.637] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:52:33.637] INFO: ----------------------------------------------------------------------
[14:52:33.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:52:33.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:52:33.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:52:33.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:52:33.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:52:33.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:52:33.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:52:33.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:52:33.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:52:33.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:52:33.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:52:33.637] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:52:33.655] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:52:34.325] INFO: Expecting 208000 events.
[14:52:49.255] INFO: 208000 events read in total (14404ms).
[14:52:49.272] INFO: Test took 15617ms.
[14:52:49.807] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:49.807] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3063605
[14:52:49.807] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:52:49.807] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:50.243] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:52:50.243] INFO: number of red-efficiency pixels: 181 141 282 439 452 501 434 366 398 474 455 477 407 260 135 185
[14:52:50.243] INFO: number of X-ray hits detected: 129628 90394 151162 227225 231892 236978 222853 203314 215423 238123 236845 228615 223215 151415 91705 118258
[14:52:50.243] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:52:50.243] INFO: number of Vcal hits detected: 207806 207856 207698 207526 207503 207451 207539 207601 207562 207491 207499 207483 207553 207728 207858 207810
[14:52:50.243] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[14:52:50.243] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[14:52:50.243] INFO: X-ray hit rate [MHz/cm2]: 38.0 26.5 44.3 66.6 68.0 69.5 65.3 59.6 63.1 69.8 69.4 67.0 65.4 44.4 26.9 34.7
[14:52:50.243] INFO: PixTestHighRate::doXPixelAlive() done
[14:52:50.292] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:52:50.292] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:52:50.292] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:52:50.292] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:52:50.292] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:52:50.292] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:52:50.292] INFO: PixTest:: pg_setup set to default.
[14:52:55.588] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:52:55.588] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[14:52:57.860] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[14:52:57.860] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[14:52:57.861] INFO: ----------------------------------------------------------------------
[14:52:57.861] INFO: PixTestHighRate::runDaq() running for 2 seconds
[14:52:57.861] INFO: ----------------------------------------------------------------------
[14:52:58.062] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[14:52:58.062] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[14:52:58.062] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[14:52:58.062] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[14:52:58.062] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[14:52:58.062] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[14:52:58.062] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[14:52:58.062] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[14:52:58.062] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[14:52:58.062] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[14:52:58.062] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[14:52:58.062] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[14:52:59.020] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[14:53:01.023] INFO: data taking finished, elapsed time: 2 seconds.
[14:53:02.754] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39827 events with 569104 pixels
[14:53:02.761] INFO: PixTest:: pg_setup set to default.
[14:53:02.882] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[14:53:02.882] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[14:53:02.882] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[14:53:02.882] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[14:53:02.882] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[14:53:02.882] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[14:53:02.883] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[14:53:02.883] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[14:53:02.883] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[14:53:02.883] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[14:53:02.883] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[14:53:02.883] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[14:53:02.883] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[14:53:02.884] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[14:53:02.884] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[14:53:02.884] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[14:53:02.884] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[14:53:02.884] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[14:53:02.884] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[14:53:02.884] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[14:53:02.884] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[14:53:02.884] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[14:53:02.884] INFO: Pixels without X-ray hits (per ROC): 482 976 595 118 124 111 135 145 131 105 113 227 122 552 982 486
[14:53:02.884] INFO: PixTestHighRate::doRunDaq() done
[14:53:22.372] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:53:22.372] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C12_V0 -> hitMap_daqbbtest_mod
[14:53:24.924] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[14:53:24.925] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 383.5mA
[14:53:24.926] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[14:53:24.926] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[14:53:24.926] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[14:53:24.926] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[14:53:24.926] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[14:53:24.926] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[14:53:24.926] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[14:53:24.927] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[14:53:24.927] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[14:53:24.927] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[14:53:24.927] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[14:53:24.927] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:53:25.033] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[14:53:25.033] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:53:25.047] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[14:53:25.047] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[14:53:25.047] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[14:53:25.047] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[14:53:25.047] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[14:53:25.047] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:53:25.049] QUIET: Connection to board 58 closed.
[14:53:25.129] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[14:46:46.030] INFO: *** Welcome to pxar ***
[14:46:46.030] INFO: *** Today: 2016/09/16
[14:46:46.058] INFO: *** Version: v1.9.0-818-g96727
[14:46:46.058] INFO: readRocDacs: data/mqa40/dacParameters35_C0.dat .. data/mqa40/dacParameters35_C15.dat
[14:46:46.059] INFO: readTbmDacs: data/mqa40/tbmParameters_C0a.dat .. data/mqa40/tbmParameters_C0b.dat
[14:46:46.059] INFO: readMaskFile: data/mqa40/defaultMaskFile.dat
[14:46:46.059] INFO: readTrimFile: data/mqa40/trimParameters35_C0.dat .. data/mqa40/trimParameters35_C15.dat
[14:46:46.212] INFO: clk: 4
[14:46:46.212] INFO: ctr: 4
[14:46:46.212] INFO: sda: 19
[14:46:46.212] INFO: tin: 9
[14:46:46.212] INFO: level: 15
[14:46:46.212] INFO: triggerdelay: 0
[14:46:46.212] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[14:46:46.212] INFO: Log level: DEBUG
[14:46:46.223] QUIET: Connection to board DTB_WRPRHI opened.
[14:46:46.226] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[14:46:46.229] INFO: RPC call hashes of host and DTB match: 398089610
[14:46:47.830] INFO: DUT info:
[14:46:47.830] INFO: The DUT currently contains the following objects:
[14:46:47.830] INFO: 2 TBM Cores tbm08c (2 ON)
[14:46:47.830] INFO: TBM Core alpha (0): 7 registers set
[14:46:47.830] INFO: TBM Core beta (1): 7 registers set
[14:46:47.830] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:46:47.830] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.830] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.830] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.830] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.830] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.830] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.830] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.830] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.830] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.830] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.830] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.830] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.830] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.830] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.830] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.830] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:46:47.831] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:46:47.832] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:46:47.833] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:46:47.834] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:46:47.836] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30330880
[14:46:47.836] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x161f0e0
[14:46:47.836] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x13c1370
[14:46:47.836] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f5341d94010
[14:46:47.836] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f5347fff510
[14:46:47.836] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30339072 fPxarMemory = 0x7f5341d94010
[14:46:47.837] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 378.6mA
[14:46:47.839] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[14:46:47.839] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[14:46:48.335] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1a0e030
[14:46:48.396] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:46:48.396] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:46:48.396] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:46:48.402] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:46:48.402] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:46:48.402] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:46:48.414] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:46:48.414] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:46:48.432] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:46:48.432] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:46:48.443] INFO: PixTestCmd::init()
[14:46:48.450] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:46:48.450] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:46:48.450] INFO: readGainPedestalParameters data/mqa40/phCalibrationFitErr35_C0.dat .. data/mqa40/phCalibrationFitErr35_C15.dat
[14:46:48.700] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:46:48.700] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:46:48.714] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:46:48.720] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:46:48.720] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:46:48.720] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:46:48.740] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:46:48.740] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:46:48.740] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:46:48.740] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:46:48.740] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:46:48.746] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:46:48.746] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:46:48.762] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:46:48.762] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:46:48.762] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:46:48.762] INFO: readReadbackCal: data/mqa40/readbackCal_C0.dat .. data/mqa40/readbackCal_C15.dat
[14:46:48.773] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:46:48.780] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[14:46:48.780] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[14:46:48.780] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[14:46:48.781] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[14:46:48.799] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:46:48.799] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:46:58.672] INFO: ######################################################################
[14:46:58.672] INFO: PixTestAlive::doTest()
[14:46:58.672] INFO: ######################################################################
[14:46:58.675] INFO: ----------------------------------------------------------------------
[14:46:58.675] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:46:58.675] INFO: ----------------------------------------------------------------------
[14:46:58.678] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:46:59.057] INFO: Expecting 41600 events.
[14:47:03.473] INFO: 41600 events read in total (3698ms).
[14:47:03.621] INFO: Test took 4943ms.
[14:47:03.634] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:03.635] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:47:03.635] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:47:03.911] INFO: PixTestAlive::aliveTest() done
[14:47:03.911] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:47:03.911] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 36 29 45 65 78 100 90 55 71 80 77 91 83 46 35 30
[14:47:03.940] INFO: ----------------------------------------------------------------------
[14:47:03.940] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:47:03.940] INFO: ----------------------------------------------------------------------
[14:47:03.943] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:47:04.324] INFO: Expecting 41600 events.
[14:47:07.445] INFO: 41600 events read in total (2406ms).
[14:47:07.446] INFO: Test took 3503ms.
[14:47:07.446] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:07.446] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[14:47:07.446] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[14:47:07.446] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:47:07.869] INFO: PixTestAlive::maskTest() done
[14:47:07.869] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:47:07.888] INFO: ----------------------------------------------------------------------
[14:47:07.888] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:47:07.888] INFO: ----------------------------------------------------------------------
[14:47:07.890] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:47:08.267] INFO: Expecting 41600 events.
[14:47:12.664] INFO: 41600 events read in total (3682ms).
[14:47:12.666] INFO: Test took 4776ms.
[14:47:12.677] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:12.677] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:47:12.677] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[14:47:13.046] INFO: PixTestAlive::addressDecodingTest() done
[14:47:13.046] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:47:13.046] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[14:47:17.004] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:47:17.005] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[14:47:19.420] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[14:47:19.420] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:47:19.420] INFO: ----------------------------------------------------------------------
[14:47:19.420] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:47:19.420] INFO: ----------------------------------------------------------------------
[14:47:19.560] INFO: Expecting 768 events.
[14:47:20.694] INFO: 768 events read in total (419ms).
[14:47:20.695] INFO: Test took 1269ms.
[14:47:20.700] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:47:21.498] INFO: Expecting 41600 events.
[14:47:25.302] INFO: 41600 events read in total (3278ms).
[14:47:25.308] INFO: Test took 4608ms.
[14:47:25.459] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:25.459] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 867444
[14:47:25.459] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:47:25.459] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:47:25.523] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:47:26.188] INFO: Expecting 41600 events.
[14:47:30.096] INFO: 41600 events read in total (3382ms).
[14:47:30.101] INFO: Test took 4578ms.
[14:47:30.245] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:30.245] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 876791
[14:47:30.245] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:47:30.246] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:47:30.313] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:47:30.978] INFO: Expecting 41600 events.
[14:47:34.962] INFO: 41600 events read in total (3457ms).
[14:47:34.967] INFO: Test took 4654ms.
[14:47:35.112] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:35.112] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 880451
[14:47:35.112] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:47:35.112] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:47:35.179] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:47:35.845] INFO: Expecting 41600 events.
[14:47:39.812] INFO: 41600 events read in total (3441ms).
[14:47:39.817] INFO: Test took 4638ms.
[14:47:39.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:39.963] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 881078
[14:47:39.963] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:47:39.964] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:47:40.031] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:47:40.696] INFO: Expecting 41600 events.
[14:47:44.677] INFO: 41600 events read in total (3454ms).
[14:47:44.683] INFO: Test took 4652ms.
[14:47:44.824] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:44.825] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 880724
[14:47:44.825] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:47:44.825] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:47:44.892] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:47:45.557] INFO: Expecting 41600 events.
[14:47:49.546] INFO: 41600 events read in total (3462ms).
[14:47:49.551] INFO: Test took 4659ms.
[14:47:49.697] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:49.697] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 878556
[14:47:49.697] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:47:49.697] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:47:49.765] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:47:50.432] INFO: Expecting 41600 events.
[14:47:54.394] INFO: 41600 events read in total (3436ms).
[14:47:54.400] INFO: Test took 4635ms.
[14:47:54.546] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:54.546] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 881948
[14:47:54.546] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:47:54.546] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:47:54.614] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:47:55.282] INFO: Expecting 41600 events.
[14:47:59.282] INFO: 41600 events read in total (3474ms).
[14:47:59.287] INFO: Test took 4673ms.
[14:47:59.432] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:59.432] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 884116
[14:47:59.432] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:47:59.433] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:47:59.500] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:00.165] INFO: Expecting 41600 events.
[14:48:04.169] INFO: 41600 events read in total (3478ms).
[14:48:04.174] INFO: Test took 4674ms.
[14:48:04.323] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:04.323] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 884015
[14:48:04.323] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:48:04.323] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:04.388] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:05.053] INFO: Expecting 41600 events.
[14:48:09.046] INFO: 41600 events read in total (3467ms).
[14:48:09.051] INFO: Test took 4663ms.
[14:48:09.195] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:09.195] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 882133
[14:48:09.195] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:48:09.196] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:09.262] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:09.929] INFO: Expecting 41600 events.
[14:48:13.935] INFO: 41600 events read in total (3480ms).
[14:48:13.940] INFO: Test took 4677ms.
[14:48:14.084] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:14.084] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 883092
[14:48:14.084] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:48:14.085] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:14.153] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:14.820] INFO: Expecting 41600 events.
[14:48:18.816] INFO: 41600 events read in total (3470ms).
[14:48:18.821] INFO: Test took 4668ms.
[14:48:18.964] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:18.964] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 881940
[14:48:18.964] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:48:18.965] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:19.033] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:19.699] INFO: Expecting 41600 events.
[14:48:23.695] INFO: 41600 events read in total (3469ms).
[14:48:23.701] INFO: Test took 4668ms.
[14:48:23.846] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:23.846] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 881706
[14:48:23.846] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:48:23.846] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:23.915] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:24.581] INFO: Expecting 41600 events.
[14:48:28.593] INFO: 41600 events read in total (3485ms).
[14:48:28.599] INFO: Test took 4684ms.
[14:48:28.740] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:28.740] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 879532
[14:48:28.740] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:48:28.741] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:28.807] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:29.473] INFO: Expecting 41600 events.
[14:48:33.472] INFO: 41600 events read in total (3473ms).
[14:48:33.478] INFO: Test took 4671ms.
[14:48:33.623] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:33.623] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 880357
[14:48:33.623] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:48:33.624] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:33.691] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:34.356] INFO: Expecting 41600 events.
[14:48:38.352] INFO: 41600 events read in total (3469ms).
[14:48:38.357] INFO: Test took 4666ms.
[14:48:38.502] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:38.502] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 882163
[14:48:38.502] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:48:38.502] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:38.571] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:39.237] INFO: Expecting 41600 events.
[14:48:43.244] INFO: 41600 events read in total (3480ms).
[14:48:43.249] INFO: Test took 4678ms.
[14:48:43.390] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:43.391] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 881714
[14:48:43.391] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:48:43.391] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:43.459] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:44.124] INFO: Expecting 41600 events.
[14:48:48.117] INFO: 41600 events read in total (3466ms).
[14:48:48.123] INFO: Test took 4664ms.
[14:48:48.267] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:48.267] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 882078
[14:48:48.267] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:48:48.268] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:48.337] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:49.003] INFO: Expecting 41600 events.
[14:48:52.971] INFO: 41600 events read in total (3441ms).
[14:48:52.976] INFO: Test took 4639ms.
[14:48:53.120] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:53.120] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 881400
[14:48:53.120] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:48:53.120] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:53.188] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:53.855] INFO: Expecting 41600 events.
[14:48:57.641] INFO: 41600 events read in total (3260ms).
[14:48:57.646] INFO: Test took 4458ms.
[14:48:57.790] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:57.790] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 874809
[14:48:57.790] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:48:57.790] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:58.078] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 143 eff = 0.998606
[14:48:58.078] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 132 eff = 0.999111
[14:48:58.079] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 145 eff = 0.997692
[14:48:58.079] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 172 eff = 0.996418
[14:48:58.079] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 179 eff = 0.995986
[14:48:58.079] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 164 eff = 0.994856
[14:48:58.079] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 162 eff = 0.995673
[14:48:58.079] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 159 eff = 0.995938
[14:48:58.079] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 161 eff = 0.996082
[14:48:58.080] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 153 eff = 0.995264
[14:48:58.080] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 171 eff = 0.995865
[14:48:58.080] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 176 eff = 0.995337
[14:48:58.080] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 164 eff = 0.996082
[14:48:58.080] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 175 eff = 0.99762
[14:48:58.080] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 156 eff = 0.999231
[14:48:58.080] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 144 eff = 0.998894
[14:49:11.884] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[14:49:11.884] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:49:11.884] INFO: ----------------------------------------------------------------------
[14:49:11.884] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:49:11.884] INFO: ----------------------------------------------------------------------
[14:49:11.885] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:49:11.885] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:49:11.885] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:49:11.885] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:49:11.885] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:49:11.885] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:49:11.885] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:49:11.885] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:49:11.885] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:49:11.885] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:49:11.885] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:49:11.885] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:49:11.903] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:12.573] INFO: Expecting 208000 events.
[14:49:29.327] INFO: 208000 events read in total (16228ms).
[14:49:29.348] INFO: Test took 17445ms.
[14:49:30.113] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:30.113] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4098224
[14:49:30.114] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:49:30.114] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:49:30.620] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:49:30.620] INFO: number of red-efficiency pixels: 281 235 478 717 841 859 746 666 676 821 860 796 750 418 208 230
[14:49:30.620] INFO: number of X-ray hits detected: 174237 121190 203091 306146 313335 318890 299232 272716 289386 320527 318624 305628 300865 204955 123559 159283
[14:49:30.620] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:49:30.620] INFO: number of Vcal hits detected: 207698 207750 207442 207199 207016 206979 207142 207202 207225 207022 206994 207063 207146 207518 207785 207758
[14:49:30.620] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.6 99.6 99.6 99.6 99.6 99.6 99.6 99.5 99.6 99.6 99.8 99.9 99.9
[14:49:30.620] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.6 99.5 99.5 99.6 99.6 99.6 99.5 99.5 99.5 99.6 99.8 99.9 99.9
[14:49:30.620] INFO: X-ray hit rate [MHz/cm2]: 51.1 35.5 59.5 89.7 91.8 93.5 87.7 79.9 84.8 93.9 93.4 89.6 88.2 60.1 36.2 46.7
[14:49:30.620] INFO: PixTestHighRate::doXPixelAlive() done
[14:49:30.666] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:49:30.666] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:49:30.666] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:49:30.666] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:49:30.666] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:49:30.666] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:49:30.666] INFO: PixTest:: pg_setup set to default.
[14:49:34.356] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:49:34.356] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[14:49:40.236] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[14:49:40.236] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[14:49:40.237] INFO: ----------------------------------------------------------------------
[14:49:40.237] INFO: PixTestHighRate::runDaq() running for 2 seconds
[14:49:40.237] INFO: ----------------------------------------------------------------------
[14:49:40.439] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[14:49:40.439] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[14:49:40.439] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[14:49:40.439] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[14:49:40.439] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[14:49:40.439] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[14:49:40.439] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[14:49:40.439] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[14:49:40.439] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[14:49:40.439] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[14:49:40.439] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[14:49:40.439] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[14:49:41.397] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[14:49:43.399] INFO: data taking finished, elapsed time: 2 seconds.
[14:49:45.335] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39822 events with 769845 pixels
[14:49:45.340] INFO: PixTest:: pg_setup set to default.
[14:49:45.446] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[14:49:45.446] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[14:49:45.447] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[14:49:45.447] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[14:49:45.447] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[14:49:45.447] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[14:49:45.447] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[14:49:45.447] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[14:49:45.447] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[14:49:45.448] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[14:49:45.448] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[14:49:45.448] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[14:49:45.448] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[14:49:45.448] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[14:49:45.448] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[14:49:45.448] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[14:49:45.449] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[14:49:45.449] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[14:49:45.449] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[14:49:45.449] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[14:49:45.449] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[14:49:45.449] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[14:49:45.449] INFO: Pixels without X-ray hits (per ROC): 274 780 498 79 96 95 95 112 98 82 75 186 82 476 795 347
[14:49:45.449] INFO: PixTestHighRate::doRunDaq() done
[14:49:50.652] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:49:50.652] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C13_V0 -> hitMap_daqbbtest_mod
[14:49:52.612] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[14:49:52.613] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 383.5mA
[14:49:52.614] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 469.5mA
[14:49:52.614] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[14:49:52.614] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[14:49:52.614] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[14:49:52.614] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[14:49:52.614] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[14:49:52.614] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[14:49:52.615] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[14:49:52.615] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[14:49:52.615] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[14:49:52.615] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[14:49:52.615] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:49:52.722] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[14:49:52.722] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:49:52.735] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[14:49:52.736] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[14:49:52.736] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[14:49:52.736] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[14:49:52.736] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[14:49:52.736] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:49:52.738] QUIET: Connection to board 58 closed.
[14:49:52.818] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-904-gd94b9f2 on branch master