Test Date: 1973-11-29 16:33
Analysis date: 2016-09-19 20:09
Logfile
hrData_40.log
[15:15:08.016] INFO: *** Welcome to pxar ***
[15:15:08.016] INFO: *** Today: 2016/09/19
[15:15:08.040] INFO: *** Version: v1.9.0-818-g96727
[15:15:08.040] INFO: readRocDacs: data/mqa43/dacParameters35_C0.dat .. data/mqa43/dacParameters35_C15.dat
[15:15:08.041] INFO: readTbmDacs: data/mqa43/tbmParameters_C0a.dat .. data/mqa43/tbmParameters_C0b.dat
[15:15:08.041] INFO: readMaskFile: data/mqa43/defaultMaskFile.dat
[15:15:08.041] INFO: readTrimFile: data/mqa43/trimParameters35_C0.dat .. data/mqa43/trimParameters35_C15.dat
[15:15:08.192] INFO: clk: 4
[15:15:08.192] INFO: ctr: 4
[15:15:08.192] INFO: sda: 19
[15:15:08.192] INFO: tin: 9
[15:15:08.192] INFO: level: 15
[15:15:08.192] INFO: triggerdelay: 0
[15:15:08.192] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:15:08.192] INFO: Log level: DEBUG
[15:15:08.203] QUIET: Connection to board DTB_WRPRHI opened.
[15:15:08.207] 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:15:08.209] INFO: RPC call hashes of host and DTB match: 398089610
[15:15:09.813] INFO: DUT info:
[15:15:09.813] INFO: The DUT currently contains the following objects:
[15:15:09.813] INFO: 2 TBM Cores tbm08c (2 ON)
[15:15:09.813] INFO: TBM Core alpha (0): 7 registers set
[15:15:09.813] INFO: TBM Core beta (1): 7 registers set
[15:15:09.813] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:15:09.813] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.813] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.814] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.814] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.814] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.814] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.814] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.814] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.814] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.814] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.814] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.814] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.814] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.814] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.814] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.814] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:15:09.814] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:15:09.814] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:15:09.814] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:15:09.814] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:15:09.814] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:15:09.814] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:15:09.814] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:15:09.815] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:15:09.816] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:15:09.817] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:15:09.818] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:15:09.820] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34820096
[15:15:09.820] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x181a0e0
[15:15:09.820] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x15bc370
[15:15:09.820] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fe2e5d94010
[15:15:09.820] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fe2ebfff510
[15:15:09.820] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34828288 fPxarMemory = 0x7fe2e5d94010
[15:15:09.822] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 394.7mA
[15:15:09.823] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[15:15:09.823] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[15:15:10.321] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1c09030
[15:15:10.385] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:15:10.385] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:15:10.385] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:15:10.391] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:15:10.391] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:15:10.392] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:15:10.406] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:15:10.406] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:15:10.421] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:15:10.421] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:15:10.433] INFO: PixTestCmd::init()
[15:15:10.446] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:15:10.446] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:15:10.446] INFO: readGainPedestalParameters data/mqa43/phCalibrationFitErr35_C0.dat .. data/mqa43/phCalibrationFitErr35_C15.dat
[15:15:10.694] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:15:10.694] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:15:10.708] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:15:10.715] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:15:10.715] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:15:10.715] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:15:10.743] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:15:10.743] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:15:10.743] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:15:10.743] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:15:10.743] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:15:10.749] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:15:10.749] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:15:10.771] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:15:10.771] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:15:10.771] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:15:10.771] INFO: readReadbackCal: data/mqa43/readbackCal_C0.dat .. data/mqa43/readbackCal_C15.dat
[15:15:10.781] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:15:10.792] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:15:10.792] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:15:10.792] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:15:10.792] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:15:10.811] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:15:10.811] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:15:16.185] INFO: ######################################################################
[15:15:16.185] INFO: PixTestXray::doTest()
[15:15:16.185] INFO: ######################################################################
[15:15:16.185] INFO: ----------------------------------------------------------------------
[15:15:16.185] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:15:16.185] INFO: ----------------------------------------------------------------------
[15:15:17.169] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:15:29.242] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[15:15:29.246] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:15:56.536] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1189564 events.
[15:16:03.245] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1189564, pixels seen in all events: 5977215
[15:16:03.438] INFO: Resuming triggers.
[15:16:15.503] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[15:16:15.538] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:16:42.855] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1188588 events.
[15:16:49.491] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1188588, pixels seen in all events: 5988171
[15:16:49.667] INFO: Resuming triggers.
[15:17:01.741] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[15:17:01.852] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:17:28.836] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1189463 events.
[15:17:35.579] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1189463, pixels seen in all events: 5977763
[15:17:35.752] INFO: Resuming triggers.
[15:17:47.823] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[15:17:47.856] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:18:15.010] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1189168 events.
[15:18:21.674] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1189168, pixels seen in all events: 5981315
[15:18:21.939] INFO: Resuming triggers.
[15:18:34.009] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[15:18:34.041] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:19:01.228] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1189057 events.
[15:19:07.972] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1189057, pixels seen in all events: 5982330
[15:19:08.071] INFO: Resuming triggers.
[15:19:20.142] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[15:19:20.178] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:19:47.229] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1189132 events.
[15:19:53.889] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1189132, pixels seen in all events: 5981637
[15:19:54.149] INFO: Resuming triggers.
[15:20:06.216] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[15:20:06.252] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:20:33.407] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1188762 events.
[15:20:40.160] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1188762, pixels seen in all events: 5985983
[15:20:40.350] INFO: Resuming triggers.
[15:20:52.418] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[15:20:52.455] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:21:19.830] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1188943 events.
[15:21:26.550] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1188943, pixels seen in all events: 5984340
[15:21:26.644] INFO: Resuming triggers.
[15:21:30.216] INFO: data taking finished, elapsed time: 100 seconds.
[15:21:30.412] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:21:38.629] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 351876 events.
[15:21:40.600] DEBUG: <PixTestXray.cc/processData:L823> # events read: 351876, pixels seen in all events: 1771461
[15:21:40.677] INFO: PixTest:: pg_setup set to default.
[15:21:40.713] INFO: PixTestXray::doPhRun() done
[15:21:40.713] INFO: PixTestXray::doTest() done
[15:22:50.257] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:22:50.257] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 398.7mA
[15:22:50.258] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[15:22:50.258] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:22:50.258] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:22:50.258] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:22:50.258] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:22:50.258] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:22:50.259] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:22:50.259] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:22:50.259] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:22:50.259] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:22:50.259] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:22:50.259] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:22:50.259] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:22:50.259] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:22:50.259] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:22:50.259] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:22:50.259] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:22:50.259] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:22:50.259] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:22:50.259] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:22:50.548] QUIET: Connection to board 58 closed.
[15:22:50.627] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[15:22:54.942] INFO: *** Welcome to pxar ***
[15:22:54.942] INFO: *** Today: 2016/09/19
[15:22:54.969] INFO: *** Version: v1.9.0-818-g96727
[15:22:54.969] INFO: readRocDacs: data/mqa43/dacParameters35_C0.dat .. data/mqa43/dacParameters35_C15.dat
[15:22:54.970] INFO: readTbmDacs: data/mqa43/tbmParameters_C0a.dat .. data/mqa43/tbmParameters_C0b.dat
[15:22:54.970] INFO: readMaskFile: data/mqa43/defaultMaskFile.dat
[15:22:54.970] INFO: readTrimFile: data/mqa43/trimParameters35_C0.dat .. data/mqa43/trimParameters35_C15.dat
[15:22:55.121] INFO: clk: 4
[15:22:55.121] INFO: ctr: 4
[15:22:55.121] INFO: sda: 19
[15:22:55.121] INFO: tin: 9
[15:22:55.121] INFO: level: 15
[15:22:55.121] INFO: triggerdelay: 0
[15:22:55.121] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:22:55.121] INFO: Log level: DEBUG
[15:22:55.132] QUIET: Connection to board DTB_WRPRHI opened.
[15:22:55.135] 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:22:55.138] INFO: RPC call hashes of host and DTB match: 398089610
[15:22:56.745] INFO: DUT info:
[15:22:56.745] INFO: The DUT currently contains the following objects:
[15:22:56.745] INFO: 2 TBM Cores tbm08c (2 ON)
[15:22:56.745] INFO: TBM Core alpha (0): 7 registers set
[15:22:56.745] INFO: TBM Core beta (1): 7 registers set
[15:22:56.745] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:22:56.745] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.745] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.745] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.745] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.745] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.745] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.745] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.745] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.745] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.745] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.745] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.745] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.745] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.746] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.746] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.746] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:22:56.746] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:22:56.747] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:22:56.748] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:22:56.749] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:22:56.752] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32743424
[15:22:56.752] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xdbe0e0
[15:22:56.752] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xb60370
[15:22:56.752] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f79fdd94010
[15:22:56.752] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f7a03fff510
[15:22:56.752] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32751616 fPxarMemory = 0x7f79fdd94010
[15:22:56.753] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 394.7mA
[15:22:56.754] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[15:22:56.754] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:22:57.251] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x11acfd0
[15:22:57.315] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:22:57.315] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:22:57.316] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:22:57.321] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:22:57.322] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:22:57.322] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:22:57.333] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:22:57.333] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:22:57.352] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:22:57.352] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:22:57.367] INFO: PixTestCmd::init()
[15:22:57.386] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:22:57.386] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:22:57.386] INFO: readGainPedestalParameters data/mqa43/phCalibrationFitErr35_C0.dat .. data/mqa43/phCalibrationFitErr35_C15.dat
[15:22:57.637] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:22:57.637] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:22:57.652] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:22:57.658] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:22:57.658] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:22:57.658] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:22:57.682] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:22:57.683] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:22:57.683] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:22:57.683] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:22:57.683] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:22:57.690] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:22:57.690] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:22:57.710] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:22:57.710] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:22:57.710] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:22:57.710] INFO: readReadbackCal: data/mqa43/readbackCal_C0.dat .. data/mqa43/readbackCal_C15.dat
[15:22:57.722] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:22:57.731] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:22:57.731] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:22:57.731] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:22:57.731] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:22:57.750] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:22:57.750] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:23:03.273] INFO: ######################################################################
[15:23:03.273] INFO: PixTestXray::doTest()
[15:23:03.273] INFO: ######################################################################
[15:23:03.273] INFO: ----------------------------------------------------------------------
[15:23:03.273] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:23:03.273] INFO: ----------------------------------------------------------------------
[15:23:04.247] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:23:11.929] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:23:11.933] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:23:37.111] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 756933 events.
[15:23:49.241] DEBUG: <PixTestXray.cc/processData:L823> # events read: 756933, pixels seen in all events: 11169563
[15:23:49.488] INFO: Resuming triggers.
[15:23:57.183] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[15:23:57.226] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:24:22.302] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 758065 events.
[15:24:34.449] DEBUG: <PixTestXray.cc/processData:L823> # events read: 758065, pixels seen in all events: 11155189
[15:24:34.651] INFO: Resuming triggers.
[15:24:42.347] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:24:42.392] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:25:07.481] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 758152 events.
[15:25:19.632] DEBUG: <PixTestXray.cc/processData:L823> # events read: 758152, pixels seen in all events: 11153856
[15:25:19.834] INFO: Resuming triggers.
[15:25:27.530] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[15:25:27.600] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:25:52.774] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 758194 events.
[15:26:04.937] DEBUG: <PixTestXray.cc/processData:L823> # events read: 758194, pixels seen in all events: 11154296
[15:26:05.171] INFO: Resuming triggers.
[15:26:12.867] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[15:26:12.907] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:26:38.010] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 758237 events.
[15:26:50.148] DEBUG: <PixTestXray.cc/processData:L823> # events read: 758237, pixels seen in all events: 11152898
[15:26:50.386] INFO: Resuming triggers.
[15:26:58.080] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:26:58.112] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:27:23.245] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 757978 events.
[15:27:35.424] DEBUG: <PixTestXray.cc/processData:L823> # events read: 757978, pixels seen in all events: 11155660
[15:27:35.659] INFO: Resuming triggers.
[15:27:43.363] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[15:27:43.399] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:28:08.469] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 758939 events.
[15:28:20.590] DEBUG: <PixTestXray.cc/processData:L823> # events read: 758939, pixels seen in all events: 11144213
[15:28:20.791] INFO: Resuming triggers.
[15:28:28.494] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[15:28:28.558] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:28:53.585] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 758868 events.
[15:29:05.763] DEBUG: <PixTestXray.cc/processData:L823> # events read: 758868, pixels seen in all events: 11145818
[15:29:05.997] INFO: Resuming triggers.
[15:29:13.705] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:29:13.740] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:29:38.812] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 759322 events.
[15:29:50.930] DEBUG: <PixTestXray.cc/processData:L823> # events read: 759322, pixels seen in all events: 11139871
[15:29:51.161] INFO: Resuming triggers.
[15:29:58.861] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[15:29:58.896] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:30:23.965] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 758581 events.
[15:30:36.134] DEBUG: <PixTestXray.cc/processData:L823> # events read: 758581, pixels seen in all events: 11148393
[15:30:36.369] INFO: Resuming triggers.
[15:30:44.072] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[15:30:44.105] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:31:09.142] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 758816 events.
[15:31:21.262] DEBUG: <PixTestXray.cc/processData:L823> # events read: 758816, pixels seen in all events: 11146592
[15:31:21.494] INFO: Resuming triggers.
[15:31:29.197] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[15:31:29.233] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:31:54.392] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 758855 events.
[15:32:06.512] DEBUG: <PixTestXray.cc/processData:L823> # events read: 758855, pixels seen in all events: 11145497
[15:32:06.746] INFO: Resuming triggers.
[15:32:14.448] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[15:32:14.481] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:32:39.592] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 758716 events.
[15:32:51.721] DEBUG: <PixTestXray.cc/processData:L823> # events read: 758716, pixels seen in all events: 11146655
[15:32:51.957] INFO: Resuming triggers.
[15:32:52.008] INFO: data taking finished, elapsed time: 100 seconds.
[15:32:52.204] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:32:52.557] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 4982 events.
[15:32:52.638] DEBUG: <PixTestXray.cc/processData:L823> # events read: 4982, pixels seen in all events: 73440
[15:32:52.747] INFO: PixTest:: pg_setup set to default.
[15:32:52.786] INFO: PixTestXray::doPhRun() done
[15:32:52.786] INFO: PixTestXray::doTest() done
[15:33:06.737] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:33:06.738] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 398.7mA
[15:33:06.738] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 469.5mA
[15:33:06.738] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:33:06.738] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:33:06.738] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:33:06.739] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:33:06.739] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:33:06.739] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:33:06.739] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:33:06.739] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:33:06.739] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:33:06.739] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:33:06.739] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:33:06.739] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:33:06.739] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:33:06.739] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:33:06.739] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:33:06.739] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:33:06.740] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:33:06.740] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:33:06.740] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:33:07.075] QUIET: Connection to board 58 closed.
[15:33:07.155] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[15:09:15.503] INFO: *** Welcome to pxar ***
[15:09:15.503] INFO: *** Today: 2016/09/19
[15:09:15.526] INFO: *** Version: v1.9.0-818-g96727
[15:09:15.526] INFO: readRocDacs: data/mqa43/dacParameters35_C0.dat .. data/mqa43/dacParameters35_C15.dat
[15:09:15.527] INFO: readTbmDacs: data/mqa43/tbmParameters_C0a.dat .. data/mqa43/tbmParameters_C0b.dat
[15:09:15.527] INFO: readMaskFile: data/mqa43/defaultMaskFile.dat
[15:09:15.527] INFO: readTrimFile: data/mqa43/trimParameters35_C0.dat .. data/mqa43/trimParameters35_C15.dat
[15:09:15.678] INFO: clk: 4
[15:09:15.678] INFO: ctr: 4
[15:09:15.678] INFO: sda: 19
[15:09:15.678] INFO: tin: 9
[15:09:15.678] INFO: level: 15
[15:09:15.678] INFO: triggerdelay: 0
[15:09:15.678] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:09:15.678] INFO: Log level: DEBUG
[15:09:15.689] QUIET: Connection to board DTB_WRPRHI opened.
[15:09:15.692] 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:09:15.695] INFO: RPC call hashes of host and DTB match: 398089610
[15:09:17.297] INFO: DUT info:
[15:09:17.297] INFO: The DUT currently contains the following objects:
[15:09:17.297] INFO: 2 TBM Cores tbm08c (2 ON)
[15:09:17.297] INFO: TBM Core alpha (0): 7 registers set
[15:09:17.297] INFO: TBM Core beta (1): 7 registers set
[15:09:17.297] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:09:17.297] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.297] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.297] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.297] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.297] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.298] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.298] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.298] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.298] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.298] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.298] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.298] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.298] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.298] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.298] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.298] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:09:17.298] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:09:17.298] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:09:17.298] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:09:17.298] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:09:17.298] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:09:17.298] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:09:17.298] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:09:17.298] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:09:17.298] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:09:17.298] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:09:17.298] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:09:17.298] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:09:17.298] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:09:17.299] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:09:17.300] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:09:17.301] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:09:17.302] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:09:17.302] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:09:17.302] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:09:17.302] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:09:17.302] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:09:17.302] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:09:17.302] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:09:17.302] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:09:17.302] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:09:17.302] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:09:17.302] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:09:17.302] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:09:17.302] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:09:17.302] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:09:17.304] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32968704
[15:09:17.304] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xf5e0e0
[15:09:17.304] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xd00370
[15:09:17.304] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f652dd94010
[15:09:17.304] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f6533fff510
[15:09:17.304] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32976896 fPxarMemory = 0x7f652dd94010
[15:09:17.305] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 394.7mA
[15:09:17.306] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[15:09:17.306] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:09:17.803] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x134cfd0
[15:09:17.867] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:09:17.867] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:09:17.867] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:09:17.873] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:09:17.873] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:09:17.873] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:09:17.883] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:09:17.883] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:09:17.911] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:09:17.911] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:09:17.932] INFO: PixTestCmd::init()
[15:09:17.942] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:09:17.943] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:09:17.943] INFO: readGainPedestalParameters data/mqa43/phCalibrationFitErr35_C0.dat .. data/mqa43/phCalibrationFitErr35_C15.dat
[15:09:18.194] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:09:18.194] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:09:18.208] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:09:18.214] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:09:18.214] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:09:18.215] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:09:18.240] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:09:18.240] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:09:18.240] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:09:18.240] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:09:18.240] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:09:18.247] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:09:18.247] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:09:18.269] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:09:18.269] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:09:18.269] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:09:18.269] INFO: readReadbackCal: data/mqa43/readbackCal_C0.dat .. data/mqa43/readbackCal_C15.dat
[15:09:18.278] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:09:18.288] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:09:18.288] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:09:18.288] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:09:18.288] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:09:18.307] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:09:18.307] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:09:24.140] INFO: ######################################################################
[15:09:24.140] INFO: PixTestAlive::doTest()
[15:09:24.140] INFO: ######################################################################
[15:09:24.143] INFO: ----------------------------------------------------------------------
[15:09:24.143] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:09:24.143] INFO: ----------------------------------------------------------------------
[15:09:24.146] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:24.525] INFO: Expecting 41600 events.
[15:09:28.884] INFO: 41600 events read in total (3641ms).
[15:09:29.031] INFO: Test took 4885ms.
[15:09:29.043] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:29.043] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[15:09:29.043] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:09:29.317] INFO: PixTestAlive::aliveTest() done
[15:09:29.317] INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 1 0 0 0 0 0 0 0
[15:09:29.317] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 23 14 37 33 37 33 28 34 42 38 39 40 41 30 15 28
[15:09:29.342] INFO: ----------------------------------------------------------------------
[15:09:29.342] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:09:29.342] INFO: ----------------------------------------------------------------------
[15:09:29.344] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:29.722] INFO: Expecting 41600 events.
[15:09:32.845] INFO: 41600 events read in total (2408ms).
[15:09:32.845] INFO: Test took 3501ms.
[15:09:32.845] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:32.845] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:09:32.845] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:09:32.846] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:09:33.268] INFO: PixTestAlive::maskTest() done
[15:09:33.268] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:09:33.287] INFO: ----------------------------------------------------------------------
[15:09:33.287] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:09:33.287] INFO: ----------------------------------------------------------------------
[15:09:33.289] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:33.666] INFO: Expecting 41600 events.
[15:09:38.055] INFO: 41600 events read in total (3674ms).
[15:09:38.056] INFO: Test took 4767ms.
[15:09:38.068] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:38.068] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[15:09:38.068] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:09:38.438] INFO: PixTestAlive::addressDecodingTest() done
[15:09:38.438] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:09:38.439] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:09:49.104] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:09:49.104] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:09:49.105] INFO: ----------------------------------------------------------------------
[15:09:49.105] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:09:49.105] INFO: ----------------------------------------------------------------------
[15:09:49.244] INFO: Expecting 768 events.
[15:09:50.377] INFO: 768 events read in total (418ms).
[15:09:50.377] INFO: Test took 1267ms.
[15:09:50.383] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:51.180] INFO: Expecting 41600 events.
[15:09:54.514] INFO: 41600 events read in total (2807ms).
[15:09:54.519] INFO: Test took 4136ms.
[15:09:54.603] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:54.603] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 474154
[15:09:54.603] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:09:54.604] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:54.642] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:55.307] INFO: Expecting 41600 events.
[15:09:58.763] INFO: 41600 events read in total (2929ms).
[15:09:58.767] INFO: Test took 4125ms.
[15:09:58.847] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:58.847] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 482623
[15:09:58.847] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:09:58.848] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:58.888] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:09:59.553] INFO: Expecting 41600 events.
[15:10:03.047] INFO: 41600 events read in total (2967ms).
[15:10:03.050] INFO: Test took 4162ms.
[15:10:03.130] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:03.130] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 485723
[15:10:03.130] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:10:03.130] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:03.170] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:03.836] INFO: Expecting 41600 events.
[15:10:07.363] INFO: 41600 events read in total (3000ms).
[15:10:07.367] INFO: Test took 4197ms.
[15:10:07.446] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:07.446] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486176
[15:10:07.446] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:10:07.447] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:07.487] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:08.152] INFO: Expecting 41600 events.
[15:10:11.685] INFO: 41600 events read in total (3006ms).
[15:10:11.689] INFO: Test took 4202ms.
[15:10:11.769] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:11.769] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487281
[15:10:11.769] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:10:11.770] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:11.810] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:12.476] INFO: Expecting 41600 events.
[15:10:16.012] INFO: 41600 events read in total (3009ms).
[15:10:16.015] INFO: Test took 4205ms.
[15:10:16.096] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:16.096] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486440
[15:10:16.096] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:10:16.096] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:16.136] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:16.801] INFO: Expecting 41600 events.
[15:10:20.292] INFO: 41600 events read in total (2964ms).
[15:10:20.295] INFO: Test took 4159ms.
[15:10:20.376] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:20.376] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486177
[15:10:20.376] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:10:20.376] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:20.417] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:21.082] INFO: Expecting 41600 events.
[15:10:24.608] INFO: 41600 events read in total (2999ms).
[15:10:24.612] INFO: Test took 4195ms.
[15:10:24.691] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:24.691] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487785
[15:10:24.692] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:10:24.692] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:24.732] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:25.398] INFO: Expecting 41600 events.
[15:10:28.937] INFO: 41600 events read in total (3013ms).
[15:10:28.941] INFO: Test took 4209ms.
[15:10:29.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:29.022] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 485954
[15:10:29.022] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:10:29.022] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:29.063] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:29.728] INFO: Expecting 41600 events.
[15:10:33.254] INFO: 41600 events read in total (2999ms).
[15:10:33.258] INFO: Test took 4195ms.
[15:10:33.338] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:33.338] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 488250
[15:10:33.338] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:10:33.338] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:33.379] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:34.044] INFO: Expecting 41600 events.
[15:10:37.582] INFO: 41600 events read in total (3011ms).
[15:10:37.586] INFO: Test took 4207ms.
[15:10:37.666] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:37.666] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486224
[15:10:37.666] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:10:37.667] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:37.708] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:38.373] INFO: Expecting 41600 events.
[15:10:41.896] INFO: 41600 events read in total (2996ms).
[15:10:41.900] INFO: Test took 4192ms.
[15:10:41.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:41.980] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487589
[15:10:41.980] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:10:41.980] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:42.020] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:42.686] INFO: Expecting 41600 events.
[15:10:46.230] INFO: 41600 events read in total (3018ms).
[15:10:46.234] INFO: Test took 4214ms.
[15:10:46.315] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:46.315] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487449
[15:10:46.315] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:10:46.315] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:46.356] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:47.021] INFO: Expecting 41600 events.
[15:10:50.569] INFO: 41600 events read in total (3021ms).
[15:10:50.573] INFO: Test took 4217ms.
[15:10:50.653] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:50.653] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487793
[15:10:50.653] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:10:50.654] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:50.694] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:51.360] INFO: Expecting 41600 events.
[15:10:54.896] INFO: 41600 events read in total (3010ms).
[15:10:54.900] INFO: Test took 4206ms.
[15:10:54.980] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:54.980] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486904
[15:10:54.980] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:10:54.980] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:55.021] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:10:55.687] INFO: Expecting 41600 events.
[15:10:59.232] INFO: 41600 events read in total (3018ms).
[15:10:59.235] INFO: Test took 4214ms.
[15:10:59.317] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:59.317] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 488276
[15:10:59.317] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:10:59.317] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:10:59.358] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:11:00.023] INFO: Expecting 41600 events.
[15:11:03.557] INFO: 41600 events read in total (3007ms).
[15:11:03.561] INFO: Test took 4203ms.
[15:11:03.640] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:03.640] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 485419
[15:11:03.640] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:11:03.641] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:11:03.681] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:11:04.347] INFO: Expecting 41600 events.
[15:11:07.871] INFO: 41600 events read in total (2997ms).
[15:11:07.875] INFO: Test took 4194ms.
[15:11:07.954] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:07.954] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 487152
[15:11:07.954] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:11:07.955] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:11:07.996] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:11:08.661] INFO: Expecting 41600 events.
[15:11:12.145] INFO: 41600 events read in total (2957ms).
[15:11:12.148] INFO: Test took 4152ms.
[15:11:12.228] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:12.228] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 484738
[15:11:12.228] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:11:12.229] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:11:12.269] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:11:12.934] INFO: Expecting 41600 events.
[15:11:16.267] INFO: 41600 events read in total (2806ms).
[15:11:16.270] INFO: Test took 4001ms.
[15:11:16.349] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:16.349] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477090
[15:11:16.349] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:11:16.349] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:11:16.609] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 143 eff = 0.999663
[15:11:16.610] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 185 eff = 0.99976
[15:11:16.610] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 133 eff = 0.999399
[15:11:16.610] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 147 eff = 0.998582
[15:11:16.610] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 179 eff = 0.998918
[15:11:16.610] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 163 eff = 0.998894
[15:11:16.611] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 163 eff = 0.998918
[15:11:16.611] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 138 eff = 0.999183
[15:11:16.611] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 158 eff = 0.99887
[15:11:16.611] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 131 eff = 0.998798
[15:11:16.611] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 128 eff = 0.99887
[15:11:16.611] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 131 eff = 0.99887
[15:11:16.611] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 150 eff = 0.999087
[15:11:16.612] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 172 eff = 0.999327
[15:11:16.612] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 155 eff = 0.999832
[15:11:16.612] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 135 eff = 0.999543
[15:11:21.584] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:11:21.584] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:11:21.585] INFO: ----------------------------------------------------------------------
[15:11:21.585] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:11:21.585] INFO: ----------------------------------------------------------------------
[15:11:21.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:11:21.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:11:21.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:11:21.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:11:21.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:11:21.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:11:21.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:11:21.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:11:21.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:11:21.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:11:21.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:11:21.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:11:21.603] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:11:22.273] INFO: Expecting 208000 events.
[15:11:35.877] INFO: 208000 events read in total (13077ms).
[15:11:35.890] INFO: Test took 14287ms.
[15:11:36.272] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:36.272] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2156359
[15:11:36.272] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:11:36.273] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:11:36.652] INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 1 0 0 0 0 0 0 0
[15:11:36.652] INFO: number of red-efficiency pixels: 156 62 171 252 245 253 268 202 243 327 327 316 237 197 103 121
[15:11:36.652] INFO: number of X-ray hits detected: 93767 60187 105190 156263 165420 161379 156667 142770 148590 167082 165443 153684 157828 107342 64036 84154
[15:11:36.652] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:11:36.652] INFO: number of Vcal hits detected: 207843 207937 207823 207638 207746 207742 207719 207794 207700 207656 207657 207666 207758 207799 207895 207877
[15:11:36.652] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 99.9
[15:11:36.652] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.9 99.9
[15:11:36.652] INFO: X-ray hit rate [MHz/cm2]: 27.5 17.6 30.8 45.8 48.5 47.3 45.9 41.8 43.6 49.0 48.5 45.0 46.3 31.5 18.8 24.7
[15:11:36.652] INFO: PixTestHighRate::doXPixelAlive() done
[15:11:36.696] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:11:36.696] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:11:36.696] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:11:36.696] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:11:36.696] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:11:36.696] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:11:36.697] INFO: PixTest:: pg_setup set to default.
[15:12:20.561] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:12:20.561] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 398.7mA
[15:12:20.562] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[15:12:20.562] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: 0.1 C
[15:12:20.562] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:12:20.562] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:12:20.562] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:12:20.562] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:12:20.562] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:12:20.563] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:12:20.563] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:12:20.563] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:12:20.563] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:12:20.563] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:12:20.645] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:12:20.645] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:12:20.654] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:12:20.655] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:12:20.655] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:12:20.655] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:12:20.655] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:12:20.655] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:12:20.657] QUIET: Connection to board 58 closed.
[15:12:20.737] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[15:04:19.693] INFO: *** Welcome to pxar ***
[15:04:19.693] INFO: *** Today: 2016/09/19
[15:04:19.716] INFO: *** Version: v1.9.0-818-g96727
[15:04:19.717] INFO: readRocDacs: data/mqa43/dacParameters35_C0.dat .. data/mqa43/dacParameters35_C15.dat
[15:04:19.718] INFO: readTbmDacs: data/mqa43/tbmParameters_C0a.dat .. data/mqa43/tbmParameters_C0b.dat
[15:04:19.718] INFO: readMaskFile: data/mqa43/defaultMaskFile.dat
[15:04:19.718] INFO: readTrimFile: data/mqa43/trimParameters35_C0.dat .. data/mqa43/trimParameters35_C15.dat
[15:04:19.866] INFO: clk: 4
[15:04:19.867] INFO: ctr: 4
[15:04:19.867] INFO: sda: 19
[15:04:19.867] INFO: tin: 9
[15:04:19.867] INFO: level: 15
[15:04:19.867] INFO: triggerdelay: 0
[15:04:19.867] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:04:19.867] INFO: Log level: DEBUG
[15:04:19.878] QUIET: Connection to board DTB_WRPRHI opened.
[15:04:19.881] 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:04:19.884] INFO: RPC call hashes of host and DTB match: 398089610
[15:04:21.487] INFO: DUT info:
[15:04:21.487] INFO: The DUT currently contains the following objects:
[15:04:21.487] INFO: 2 TBM Cores tbm08c (2 ON)
[15:04:21.487] INFO: TBM Core alpha (0): 7 registers set
[15:04:21.487] INFO: TBM Core beta (1): 7 registers set
[15:04:21.487] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:04:21.487] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.487] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.487] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.487] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.487] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.487] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.488] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.488] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.488] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.488] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.488] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.488] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.488] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.488] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.488] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.488] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:04:21.488] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:04:21.489] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:04:21.490] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:04:21.491] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:04:21.494] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35246080
[15:04:21.494] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xcf90e0
[15:04:21.494] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xa9b370
[15:04:21.494] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f1c63f91010
[15:04:21.494] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f1c6a1fc510
[15:04:21.494] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35254272 fPxarMemory = 0x7f1c63f91010
[15:04:21.495] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 394.7mA
[15:04:21.496] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[15:04:21.496] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:04:22.007] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x10e8030
[15:04:22.069] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:04:22.069] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:04:22.069] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:04:22.075] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:04:22.075] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:04:22.075] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:04:22.085] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:04:22.085] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:04:22.094] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:04:22.094] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:04:22.109] INFO: PixTestCmd::init()
[15:04:22.121] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:04:22.121] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:04:22.122] INFO: readGainPedestalParameters data/mqa43/phCalibrationFitErr35_C0.dat .. data/mqa43/phCalibrationFitErr35_C15.dat
[15:04:22.376] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:04:22.376] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:04:22.392] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:04:22.399] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:04:22.399] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:04:22.399] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:04:22.425] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:04:22.425] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:04:22.425] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:04:22.425] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:04:22.425] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:04:22.431] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:04:22.431] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:04:22.454] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:04:22.454] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:04:22.454] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:04:22.454] INFO: readReadbackCal: data/mqa43/readbackCal_C0.dat .. data/mqa43/readbackCal_C15.dat
[15:04:22.463] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:04:22.479] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:04:22.479] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:04:22.479] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:04:22.480] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:04:22.493] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:04:22.493] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:04:25.524] INFO: ######################################################################
[15:04:25.524] INFO: PixTestAlive::doTest()
[15:04:25.524] INFO: ######################################################################
[15:04:25.527] INFO: ----------------------------------------------------------------------
[15:04:25.527] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:04:25.527] INFO: ----------------------------------------------------------------------
[15:04:25.530] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:04:25.908] INFO: Expecting 41600 events.
[15:04:30.298] INFO: 41600 events read in total (3672ms).
[15:04:30.446] INFO: Test took 4916ms.
[15:04:30.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:30.458] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[15:04:30.458] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:04:30.734] INFO: PixTestAlive::aliveTest() done
[15:04:30.734] INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 1 0 0 0 0 0 0 0
[15:04:30.734] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 28 16 36 67 48 48 47 54 67 53 53 38 66 40 21 25
[15:04:30.760] INFO: ----------------------------------------------------------------------
[15:04:30.760] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:04:30.760] INFO: ----------------------------------------------------------------------
[15:04:30.763] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:04:31.141] INFO: Expecting 41600 events.
[15:04:34.263] INFO: 41600 events read in total (2407ms).
[15:04:34.264] INFO: Test took 3501ms.
[15:04:34.264] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:34.264] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:04:34.264] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:04:34.264] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:04:34.686] INFO: PixTestAlive::maskTest() done
[15:04:34.686] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:04:34.710] INFO: ----------------------------------------------------------------------
[15:04:34.710] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:04:34.710] INFO: ----------------------------------------------------------------------
[15:04:34.712] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:04:35.091] INFO: Expecting 41600 events.
[15:04:39.486] INFO: 41600 events read in total (3680ms).
[15:04:39.488] INFO: Test took 4776ms.
[15:04:39.499] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:39.499] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[15:04:39.499] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:04:39.869] INFO: PixTestAlive::addressDecodingTest() done
[15:04:39.870] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:04:39.870] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:07:13.904] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:07:13.904] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:07:13.904] INFO: ----------------------------------------------------------------------
[15:07:13.904] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:07:13.904] INFO: ----------------------------------------------------------------------
[15:07:14.044] INFO: Expecting 768 events.
[15:07:15.176] INFO: 768 events read in total (418ms).
[15:07:15.177] INFO: Test took 1267ms.
[15:07:15.183] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:15.980] INFO: Expecting 41600 events.
[15:07:19.590] INFO: 41600 events read in total (3083ms).
[15:07:19.595] INFO: Test took 4412ms.
[15:07:19.709] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:19.709] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 655497
[15:07:19.709] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:07:19.710] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:19.759] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:20.425] INFO: Expecting 41600 events.
[15:07:24.150] INFO: 41600 events read in total (3198ms).
[15:07:24.154] INFO: Test took 4394ms.
[15:07:24.265] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:24.265] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 663587
[15:07:24.265] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:07:24.265] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:24.317] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:24.982] INFO: Expecting 41600 events.
[15:07:28.763] INFO: 41600 events read in total (3254ms).
[15:07:28.767] INFO: Test took 4450ms.
[15:07:28.879] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:28.879] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 666851
[15:07:28.879] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:07:28.880] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:28.931] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:29.597] INFO: Expecting 41600 events.
[15:07:33.386] INFO: 41600 events read in total (3262ms).
[15:07:33.391] INFO: Test took 4460ms.
[15:07:33.503] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:33.503] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 668158
[15:07:33.504] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:07:33.504] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:33.557] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:34.222] INFO: Expecting 41600 events.
[15:07:38.013] INFO: 41600 events read in total (3264ms).
[15:07:38.018] INFO: Test took 4461ms.
[15:07:38.130] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:38.130] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 668291
[15:07:38.130] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:07:38.130] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:38.182] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:38.847] INFO: Expecting 41600 events.
[15:07:42.644] INFO: 41600 events read in total (3270ms).
[15:07:42.648] INFO: Test took 4466ms.
[15:07:42.761] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:42.761] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 669036
[15:07:42.761] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:07:42.761] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:42.813] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:43.479] INFO: Expecting 41600 events.
[15:07:47.289] INFO: 41600 events read in total (3284ms).
[15:07:47.294] INFO: Test took 4481ms.
[15:07:47.406] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:47.406] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 669164
[15:07:47.406] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:07:47.407] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:47.458] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:48.124] INFO: Expecting 41600 events.
[15:07:51.921] INFO: 41600 events read in total (3270ms).
[15:07:51.926] INFO: Test took 4467ms.
[15:07:52.038] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:52.038] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 668232
[15:07:52.038] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:07:52.038] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:52.090] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:52.756] INFO: Expecting 41600 events.
[15:07:56.547] INFO: 41600 events read in total (3264ms).
[15:07:56.551] INFO: Test took 4460ms.
[15:07:56.663] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:56.663] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 668646
[15:07:56.663] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:07:56.664] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:07:56.716] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:07:57.381] INFO: Expecting 41600 events.
[15:08:01.214] INFO: 41600 events read in total (3306ms).
[15:08:01.219] INFO: Test took 4503ms.
[15:08:01.334] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:01.334] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 668845
[15:08:01.334] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:08:01.335] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:08:01.389] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:08:02.058] INFO: Expecting 41600 events.
[15:08:05.877] INFO: 41600 events read in total (3293ms).
[15:08:05.882] INFO: Test took 4493ms.
[15:08:05.995] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:05.995] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 669380
[15:08:05.995] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:08:05.996] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:08:06.048] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:08:06.713] INFO: Expecting 41600 events.
[15:08:10.509] INFO: 41600 events read in total (3269ms).
[15:08:10.513] INFO: Test took 4465ms.
[15:08:10.624] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:10.624] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 669700
[15:08:10.624] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:08:10.624] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:08:10.678] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:08:11.342] INFO: Expecting 41600 events.
[15:08:15.161] INFO: 41600 events read in total (3292ms).
[15:08:15.165] INFO: Test took 4487ms.
[15:08:15.278] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:15.278] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 669466
[15:08:15.278] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:08:15.279] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:08:15.331] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:08:15.997] INFO: Expecting 41600 events.
[15:08:19.820] INFO: 41600 events read in total (3296ms).
[15:08:19.824] INFO: Test took 4493ms.
[15:08:19.937] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:19.937] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 668295
[15:08:19.937] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:08:19.937] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:08:19.990] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:08:20.655] INFO: Expecting 41600 events.
[15:08:24.462] INFO: 41600 events read in total (3280ms).
[15:08:24.467] INFO: Test took 4477ms.
[15:08:24.579] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:24.579] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 668224
[15:08:24.579] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:08:24.580] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:08:24.632] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:08:25.298] INFO: Expecting 41600 events.
[15:08:29.125] INFO: 41600 events read in total (3301ms).
[15:08:29.130] INFO: Test took 4498ms.
[15:08:29.241] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:29.241] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 667884
[15:08:29.241] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:08:29.242] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:08:29.294] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:08:29.960] INFO: Expecting 41600 events.
[15:08:33.775] INFO: 41600 events read in total (3288ms).
[15:08:33.779] INFO: Test took 4485ms.
[15:08:33.894] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:33.894] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 668705
[15:08:33.894] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:08:33.894] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:08:33.947] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:08:34.612] INFO: Expecting 41600 events.
[15:08:38.432] INFO: 41600 events read in total (3293ms).
[15:08:38.437] INFO: Test took 4490ms.
[15:08:38.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:38.552] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 668365
[15:08:38.552] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:08:38.552] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:08:38.605] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:08:39.270] INFO: Expecting 41600 events.
[15:08:43.050] INFO: 41600 events read in total (3254ms).
[15:08:43.054] INFO: Test took 4449ms.
[15:08:43.166] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:43.166] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 668250
[15:08:43.166] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:08:43.167] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:08:43.219] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:08:43.884] INFO: Expecting 41600 events.
[15:08:47.472] INFO: 41600 events read in total (3061ms).
[15:08:47.477] INFO: Test took 4258ms.
[15:08:47.584] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:08:47.584] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 658090
[15:08:47.584] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:08:47.584] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:08:47.858] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 153 eff = 0.999231
[15:08:47.858] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 166 eff = 0.999639
[15:08:47.858] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 151 eff = 0.998894
[15:08:47.858] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 148 eff = 0.997572
[15:08:47.858] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 181 eff = 0.997692
[15:08:47.859] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 163 eff = 0.997885
[15:08:47.859] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 182 eff = 0.998221
[15:08:47.859] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 140 eff = 0.998149
[15:08:47.859] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 160 eff = 0.997933
[15:08:47.859] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 155 eff = 0.99738
[15:08:47.859] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 144 eff = 0.997188
[15:08:47.859] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 154 eff = 0.997957
[15:08:47.860] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 150 eff = 0.997981
[15:08:47.860] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 178 eff = 0.998822
[15:08:47.860] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 149 eff = 0.999567
[15:08:47.860] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 154 eff = 0.999399
[15:08:50.481] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:08:50.481] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:08:50.481] INFO: ----------------------------------------------------------------------
[15:08:50.481] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:08:50.481] INFO: ----------------------------------------------------------------------
[15:08:50.481] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:08:50.481] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:08:50.481] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:08:50.481] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:08:50.481] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:08:50.481] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:08:50.481] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:08:50.481] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:08:50.481] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:08:50.481] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:08:50.481] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:08:50.481] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:08:50.502] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:08:51.173] INFO: Expecting 208000 events.
[15:09:06.022] INFO: 208000 events read in total (14323ms).
[15:09:06.039] INFO: Test took 15537ms.
[15:09:06.629] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:06.629] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3047157
[15:09:06.629] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:09:06.630] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:09:07.067] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 1 0 0 0 0 0 0 0
[15:09:07.068] INFO: number of red-efficiency pixels: 187 130 271 412 492 399 388 358 424 468 533 402 451 251 137 138
[15:09:07.068] INFO: number of X-ray hits detected: 132821 86614 150387 222492 237323 228823 223001 203247 211679 238161 234829 219505 225105 153842 92873 119897
[15:09:07.068] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:09:07.068] INFO: number of Vcal hits detected: 207806 207868 207708 207448 207452 207567 207578 207614 207489 207482 207414 207564 207529 207724 207858 207859
[15:09:07.068] 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.7 99.8 99.8 99.9 99.9 99.9
[15:09:07.068] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.7 99.7 99.8 99.8 99.8 99.8 99.8 99.7 99.8 99.8 99.9 99.9 99.9
[15:09:07.068] INFO: X-ray hit rate [MHz/cm2]: 38.9 25.4 44.1 65.2 69.6 67.1 65.4 59.6 62.0 69.8 68.8 64.3 66.0 45.1 27.2 35.1
[15:09:07.068] INFO: PixTestHighRate::doXPixelAlive() done
[15:09:07.115] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:09:07.115] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:09:07.115] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:09:07.115] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:09:07.115] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:09:07.115] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:09:07.115] INFO: PixTest:: pg_setup set to default.
[15:09:10.096] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:09:10.097] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 398.7mA
[15:09:10.098] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[15:09:10.098] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[15:09:10.098] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:09:10.098] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:09:10.098] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:09:10.098] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:09:10.098] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:09:10.098] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:09:10.098] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:09:10.099] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:09:10.099] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:09:10.099] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:09:10.181] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:09:10.181] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:09:10.190] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:09:10.190] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:09:10.190] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:09:10.190] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:09:10.190] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:09:10.190] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:09:10.193] QUIET: Connection to board 58 closed.
[15:09:10.272] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[15:01:21.744] INFO: *** Welcome to pxar ***
[15:01:21.744] INFO: *** Today: 2016/09/19
[15:01:21.770] INFO: *** Version: v1.9.0-818-g96727
[15:01:21.771] INFO: readRocDacs: data/mqa43/dacParameters35_C0.dat .. data/mqa43/dacParameters35_C15.dat
[15:01:21.772] INFO: readTbmDacs: data/mqa43/tbmParameters_C0a.dat .. data/mqa43/tbmParameters_C0b.dat
[15:01:21.772] INFO: readMaskFile: data/mqa43/defaultMaskFile.dat
[15:01:21.772] INFO: readTrimFile: data/mqa43/trimParameters35_C0.dat .. data/mqa43/trimParameters35_C15.dat
[15:01:21.920] INFO: clk: 4
[15:01:21.920] INFO: ctr: 4
[15:01:21.920] INFO: sda: 19
[15:01:21.920] INFO: tin: 9
[15:01:21.920] INFO: level: 15
[15:01:21.920] INFO: triggerdelay: 0
[15:01:21.920] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:01:21.920] INFO: Log level: DEBUG
[15:01:21.931] QUIET: Connection to board DTB_WRPRHI opened.
[15:01:21.935] 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:01:21.937] INFO: RPC call hashes of host and DTB match: 398089610
[15:01:23.541] INFO: DUT info:
[15:01:23.541] INFO: The DUT currently contains the following objects:
[15:01:23.541] INFO: 2 TBM Cores tbm08c (2 ON)
[15:01:23.542] INFO: TBM Core alpha (0): 7 registers set
[15:01:23.542] INFO: TBM Core beta (1): 7 registers set
[15:01:23.542] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:01:23.542] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:01:23.542] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:01:23.543] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:01:23.544] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:01:23.545] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:01:23.546] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:01:23.548] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32841728
[15:01:23.548] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1d600e0
[15:01:23.548] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1b02370
[15:01:23.548] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fd7ddd94010
[15:01:23.548] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fd7e3fff510
[15:01:23.548] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32849920 fPxarMemory = 0x7fd7ddd94010
[15:01:23.550] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 394.7mA
[15:01:23.551] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[15:01:23.551] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:01:24.050] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x214ef70
[15:01:24.115] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:01:24.115] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:01:24.116] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:01:24.121] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:01:24.121] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:01:24.121] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:01:24.132] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:01:24.132] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:01:24.154] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:01:24.154] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:01:24.172] INFO: PixTestCmd::init()
[15:01:24.184] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:01:24.184] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:01:24.184] INFO: readGainPedestalParameters data/mqa43/phCalibrationFitErr35_C0.dat .. data/mqa43/phCalibrationFitErr35_C15.dat
[15:01:24.435] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:01:24.435] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:01:24.448] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:01:24.454] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:01:24.455] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:01:24.455] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:01:24.482] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:01:24.482] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:01:24.482] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:01:24.482] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:01:24.482] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:01:24.488] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:01:24.489] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:01:24.514] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:01:24.514] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:01:24.514] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:01:24.514] INFO: readReadbackCal: data/mqa43/readbackCal_C0.dat .. data/mqa43/readbackCal_C15.dat
[15:01:24.525] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:01:24.542] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:01:24.542] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:01:24.542] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:01:24.542] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:01:24.555] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:01:24.555] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:01:27.076] INFO: ######################################################################
[15:01:27.076] INFO: PixTestAlive::doTest()
[15:01:27.076] INFO: ######################################################################
[15:01:27.079] INFO: ----------------------------------------------------------------------
[15:01:27.079] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:01:27.079] INFO: ----------------------------------------------------------------------
[15:01:27.082] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:27.462] INFO: Expecting 41600 events.
[15:01:31.855] INFO: 41600 events read in total (3675ms).
[15:01:32.001] INFO: Test took 4919ms.
[15:01:32.013] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:32.013] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[15:01:32.013] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:01:32.283] INFO: PixTestAlive::aliveTest() done
[15:01:32.283] INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 1 0 0 0 0 0 0 0
[15:01:32.283] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 47 20 46 74 88 76 56 79 65 79 89 86 80 47 35 33
[15:01:32.309] INFO: ----------------------------------------------------------------------
[15:01:32.309] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:01:32.309] INFO: ----------------------------------------------------------------------
[15:01:32.311] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:32.687] INFO: Expecting 41600 events.
[15:01:35.795] INFO: 41600 events read in total (2392ms).
[15:01:35.796] INFO: Test took 3485ms.
[15:01:35.796] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:35.796] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:01:35.796] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:01:35.796] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:01:36.217] INFO: PixTestAlive::maskTest() done
[15:01:36.217] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:01:36.238] INFO: ----------------------------------------------------------------------
[15:01:36.238] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:01:36.238] INFO: ----------------------------------------------------------------------
[15:01:36.240] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:36.617] INFO: Expecting 41600 events.
[15:01:41.000] INFO: 41600 events read in total (3668ms).
[15:01:41.001] INFO: Test took 4761ms.
[15:01:41.012] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:41.012] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[15:01:41.012] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:01:41.383] INFO: PixTestAlive::addressDecodingTest() done
[15:01:41.383] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:01:41.383] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:01:44.224] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:01:44.224] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:01:44.225] INFO: ----------------------------------------------------------------------
[15:01:44.225] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:01:44.225] INFO: ----------------------------------------------------------------------
[15:01:44.364] INFO: Expecting 768 events.
[15:01:45.497] INFO: 768 events read in total (418ms).
[15:01:45.497] INFO: Test took 1267ms.
[15:01:45.503] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:46.300] INFO: Expecting 41600 events.
[15:01:50.193] INFO: 41600 events read in total (3366ms).
[15:01:50.199] INFO: Test took 4696ms.
[15:01:50.356] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:50.356] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 910463
[15:01:50.357] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:01:50.357] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:50.422] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:51.088] INFO: Expecting 41600 events.
[15:01:55.068] INFO: 41600 events read in total (3454ms).
[15:01:55.074] INFO: Test took 4652ms.
[15:01:55.222] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:01:55.222] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 914846
[15:01:55.222] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:01:55.222] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:01:55.291] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:01:55.956] INFO: Expecting 41600 events.
[15:01:59.971] INFO: 41600 events read in total (3488ms).
[15:01:59.977] INFO: Test took 4686ms.
[15:02:00.127] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:00.127] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 918298
[15:02:00.127] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:02:00.127] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:00.197] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:00.864] INFO: Expecting 41600 events.
[15:02:04.905] INFO: 41600 events read in total (3515ms).
[15:02:04.911] INFO: Test took 4714ms.
[15:02:05.063] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:05.063] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 920356
[15:02:05.063] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:02:05.063] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:05.133] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:05.798] INFO: Expecting 41600 events.
[15:02:09.842] INFO: 41600 events read in total (3517ms).
[15:02:09.848] INFO: Test took 4715ms.
[15:02:09.998] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:09.998] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 921425
[15:02:09.998] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:02:09.999] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:10.068] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:10.733] INFO: Expecting 41600 events.
[15:02:14.793] INFO: 41600 events read in total (3533ms).
[15:02:14.798] INFO: Test took 4730ms.
[15:02:14.947] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:14.947] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 921505
[15:02:14.947] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:02:14.947] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:15.016] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:15.681] INFO: Expecting 41600 events.
[15:02:19.733] INFO: 41600 events read in total (3525ms).
[15:02:19.739] INFO: Test took 4723ms.
[15:02:19.894] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:19.894] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 920463
[15:02:19.894] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:02:19.894] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:19.961] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:20.627] INFO: Expecting 41600 events.
[15:02:24.691] INFO: 41600 events read in total (3537ms).
[15:02:24.697] INFO: Test took 4736ms.
[15:02:24.846] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:24.846] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 921425
[15:02:24.846] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:02:24.846] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:24.915] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:25.580] INFO: Expecting 41600 events.
[15:02:29.631] INFO: 41600 events read in total (3524ms).
[15:02:29.637] INFO: Test took 4722ms.
[15:02:29.785] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:29.785] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 921665
[15:02:29.785] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:02:29.785] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:29.856] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:30.522] INFO: Expecting 41600 events.
[15:02:34.588] INFO: 41600 events read in total (3539ms).
[15:02:34.593] INFO: Test took 4737ms.
[15:02:34.745] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:34.745] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 921672
[15:02:34.745] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:02:34.746] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:34.816] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:35.481] INFO: Expecting 41600 events.
[15:02:39.545] INFO: 41600 events read in total (3537ms).
[15:02:39.551] INFO: Test took 4735ms.
[15:02:39.700] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:39.700] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 920220
[15:02:39.700] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:02:39.700] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:39.770] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:40.435] INFO: Expecting 41600 events.
[15:02:44.500] INFO: 41600 events read in total (3539ms).
[15:02:44.506] INFO: Test took 4736ms.
[15:02:44.655] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:44.655] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 922356
[15:02:44.655] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:02:44.656] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:44.725] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:45.390] INFO: Expecting 41600 events.
[15:02:49.457] INFO: 41600 events read in total (3540ms).
[15:02:49.463] INFO: Test took 4738ms.
[15:02:49.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:49.617] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 921636
[15:02:49.617] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:02:49.617] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:49.684] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:50.349] INFO: Expecting 41600 events.
[15:02:54.418] INFO: 41600 events read in total (3542ms).
[15:02:54.423] INFO: Test took 4739ms.
[15:02:54.576] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:54.576] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 919896
[15:02:54.576] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:02:54.577] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:54.645] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:02:55.310] INFO: Expecting 41600 events.
[15:02:59.364] INFO: 41600 events read in total (3527ms).
[15:02:59.370] INFO: Test took 4725ms.
[15:02:59.522] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:02:59.522] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 919127
[15:02:59.522] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:02:59.523] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:02:59.590] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:00.255] INFO: Expecting 41600 events.
[15:03:04.315] INFO: 41600 events read in total (3533ms).
[15:03:04.321] INFO: Test took 4731ms.
[15:03:04.473] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:04.473] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 920245
[15:03:04.474] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:03:04.474] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:04.544] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:05.209] INFO: Expecting 41600 events.
[15:03:09.284] INFO: 41600 events read in total (3548ms).
[15:03:09.289] INFO: Test took 4745ms.
[15:03:09.440] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:09.440] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 920527
[15:03:09.440] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:03:09.440] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:09.510] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:10.175] INFO: Expecting 41600 events.
[15:03:14.235] INFO: 41600 events read in total (3533ms).
[15:03:14.241] INFO: Test took 4731ms.
[15:03:14.393] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:14.393] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 918960
[15:03:14.393] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:03:14.393] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:14.463] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:15.128] INFO: Expecting 41600 events.
[15:03:19.151] INFO: 41600 events read in total (3496ms).
[15:03:19.156] INFO: Test took 4693ms.
[15:03:19.306] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:19.306] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 919596
[15:03:19.306] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:03:19.307] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:19.378] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:20.043] INFO: Expecting 41600 events.
[15:03:23.884] INFO: 41600 events read in total (3314ms).
[15:03:23.890] INFO: Test took 4512ms.
[15:03:24.037] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:03:24.037] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 911880
[15:03:24.037] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:03:24.038] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:03:24.328] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 156 eff = 0.998654
[15:03:24.328] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 183 eff = 0.999327
[15:03:24.329] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 151 eff = 0.997043
[15:03:24.329] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 153 eff = 0.994904
[15:03:24.329] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 179 eff = 0.995337
[15:03:24.330] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 164 eff = 0.995361
[15:03:24.330] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 183 eff = 0.995457
[15:03:24.330] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 138 eff = 0.99601
[15:03:24.330] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 157 eff = 0.995529
[15:03:24.331] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 152 eff = 0.994375
[15:03:24.331] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 148 eff = 0.994639
[15:03:24.331] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 157 eff = 0.99512
[15:03:24.331] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 149 eff = 0.995481
[15:03:24.332] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 179 eff = 0.997404
[15:03:24.332] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 149 eff = 0.998798
[15:03:24.332] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 153 eff = 0.998918
[15:03:51.896] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:03:51.896] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:03:51.896] INFO: ----------------------------------------------------------------------
[15:03:51.896] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:03:51.896] INFO: ----------------------------------------------------------------------
[15:03:51.897] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:03:51.897] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:03:51.897] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:03:51.897] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:03:51.897] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:03:51.897] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:03:51.897] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:03:51.897] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:03:51.897] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:03:51.897] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:03:51.897] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:03:51.897] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:03:51.914] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:03:52.581] INFO: Expecting 208000 events.
[15:04:09.937] INFO: 208000 events read in total (16830ms).
[15:04:09.958] INFO: Test took 18043ms.
[15:04:10.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:04:10.994] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4282828
[15:04:10.994] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:04:10.995] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:04:11.508] INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 1 0 0 0 0 0 0 0
[15:04:11.508] INFO: number of red-efficiency pixels: 328 154 506 774 928 885 820 675 758 1039 881 803 832 519 226 269
[15:04:11.508] INFO: number of X-ray hits detected: 188425 121639 212918 314644 335614 324344 315175 287089 301338 336538 331790 310395 318363 216511 131353 170135
[15:04:11.508] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:04:11.508] INFO: number of Vcal hits detected: 207650 207835 207418 206992 206901 206982 207015 207219 207088 206722 206932 207005 207036 207394 207749 207718
[15:04:11.508] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.6 99.5 99.5 99.6 99.6 99.6 99.4 99.5 99.6 99.6 99.7 99.9 99.9
[15:04:11.508] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.5 99.5 99.5 99.5 99.6 99.6 99.4 99.5 99.5 99.5 99.7 99.9 99.9
[15:04:11.508] INFO: X-ray hit rate [MHz/cm2]: 55.2 35.7 62.4 92.2 98.4 95.1 92.4 84.1 88.3 98.6 97.3 91.0 93.3 63.5 38.5 49.9
[15:04:11.508] INFO: PixTestHighRate::doXPixelAlive() done
[15:04:11.553] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:04:11.553] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:04:11.553] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:04:11.553] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:04:11.553] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:04:11.553] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:04:11.553] INFO: PixTest:: pg_setup set to default.
[15:04:14.464] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:04:14.465] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 398.7mA
[15:04:14.466] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[15:04:14.466] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[15:04:14.466] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:04:14.466] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:04:14.466] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:04:14.466] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:04:14.466] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:04:14.466] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:04:14.467] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:04:14.467] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:04:14.467] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:04:14.467] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:04:14.550] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:04:14.551] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:04:14.560] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:04:14.560] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:04:14.560] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:04:14.560] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:04:14.560] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:04:14.560] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:04:14.563] QUIET: Connection to board 58 closed.
[15:04:14.643] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-904-gd94b9f2 on branch master