Test Date: 1973-11-29 16:33
Analysis date: 2016-10-14 18:03
Logfile
hrData_40.log
[16:19:20.397] INFO: *** Welcome to pxar ***
[16:19:20.397] INFO: *** Today: 2016/10/14
[16:19:20.421] INFO: *** Version: v1.9.0-818-g96727
[16:19:20.421] INFO: readRocDacs: data/mq839/dacParameters35_C0.dat .. data/mq839/dacParameters35_C15.dat
[16:19:20.422] INFO: readTbmDacs: data/mq839/tbmParameters_C0a.dat .. data/mq839/tbmParameters_C0b.dat
[16:19:20.422] INFO: readMaskFile: data/mq839/defaultMaskFile.dat
[16:19:20.422] INFO: readTrimFile: data/mq839/trimParameters35_C0.dat .. data/mq839/trimParameters35_C15.dat
[16:19:20.574] INFO: clk: 4
[16:19:20.574] INFO: ctr: 4
[16:19:20.574] INFO: sda: 19
[16:19:20.574] INFO: tin: 9
[16:19:20.574] INFO: level: 15
[16:19:20.574] INFO: triggerdelay: 0
[16:19:20.574] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:19:20.574] INFO: Log level: DEBUG
[16:19:20.585] QUIET: Connection to board DTB_WRPRHI opened.
[16:19:20.588] 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:
------------------------------------------------------
[16:19:20.591] INFO: RPC call hashes of host and DTB match: 398089610
[16:19:22.195] INFO: DUT info:
[16:19:22.195] INFO: The DUT currently contains the following objects:
[16:19:22.195] INFO: 2 TBM Cores tbm08c (2 ON)
[16:19:22.195] INFO: TBM Core alpha (0): 7 registers set
[16:19:22.195] INFO: TBM Core beta (1): 7 registers set
[16:19:22.195] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:19:22.195] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.195] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.195] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.195] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.195] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.195] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.195] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.195] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.195] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.195] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.195] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.195] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.195] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.195] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.196] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.196] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:19:22.196] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:19:22.197] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:19:22.198] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:19:22.199] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:19:22.201] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31375360
[16:19:22.201] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1d3f170
[16:19:22.201] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1ccd370
[16:19:22.201] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7feb3dd94010
[16:19:22.201] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7feb43fff510
[16:19:22.201] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31383552 fPxarMemory = 0x7feb3dd94010
[16:19:22.202] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 374.6mA
[16:19:22.204] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[16:19:22.204] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:19:22.700] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2319fd0
[16:19:22.762] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:19:22.763] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:19:22.763] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:19:22.768] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:19:22.768] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:19:22.768] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:19:22.779] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:19:22.780] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:19:22.795] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:19:22.795] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:19:22.819] INFO: PixTestCmd::init()
[16:19:22.844] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:19:22.844] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:19:22.844] INFO: readGainPedestalParameters data/mq839/phCalibrationFitErr35_C0.dat .. data/mq839/phCalibrationFitErr35_C15.dat
[16:19:23.090] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:19:23.090] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:19:23.105] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:19:23.111] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:19:23.111] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:19:23.112] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:19:23.134] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:19:23.134] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:19:23.134] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:19:23.134] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:19:23.134] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:19:23.140] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:19:23.140] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:19:23.161] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:19:23.161] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:19:23.161] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:19:23.161] INFO: readReadbackCal: data/mq839/readbackCal_C0.dat .. data/mq839/readbackCal_C15.dat
[16:19:23.170] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:19:23.177] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:19:23.178] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:19:23.178] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:19:23.178] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:19:23.197] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:19:23.197] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:19:25.625] INFO: ######################################################################
[16:19:25.625] INFO: PixTestXray::doTest()
[16:19:25.625] INFO: ######################################################################
[16:19:25.625] INFO: ----------------------------------------------------------------------
[16:19:25.625] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:19:25.625] INFO: ----------------------------------------------------------------------
[16:19:26.599] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:19:38.871] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[16:19:38.874] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:20:05.958] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1209121 events.
[16:20:12.149] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1209121, pixels seen in all events: 5742498
[16:20:12.399] INFO: Resuming triggers.
[16:20:24.703] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[16:20:24.743] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:20:51.792] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1212074 events.
[16:20:58.041] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1212074, pixels seen in all events: 5706197
[16:20:58.210] INFO: Resuming triggers.
[16:21:10.530] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[16:21:10.570] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:21:37.643] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1213737 events.
[16:21:43.883] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1213737, pixels seen in all events: 5686320
[16:21:44.059] INFO: Resuming triggers.
[16:21:56.384] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[16:21:56.426] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:22:23.546] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1214230 events.
[16:22:29.787] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1214230, pixels seen in all events: 5680503
[16:22:29.955] INFO: Resuming triggers.
[16:22:42.275] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[16:22:42.317] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:23:09.267] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1213724 events.
[16:23:15.437] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1213724, pixels seen in all events: 5686532
[16:23:15.685] INFO: Resuming triggers.
[16:23:28.012] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[16:23:28.050] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:23:55.179] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1214476 events.
[16:24:01.392] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1214476, pixels seen in all events: 5677646
[16:24:01.569] INFO: Resuming triggers.
[16:24:13.909] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[16:24:13.953] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:24:40.938] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1215697 events.
[16:24:47.139] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1215697, pixels seen in all events: 5662560
[16:24:47.310] INFO: Resuming triggers.
[16:24:59.647] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[16:24:59.678] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:25:26.675] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1215400 events.
[16:25:32.827] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1215400, pixels seen in all events: 5665991
[16:25:33.070] INFO: Resuming triggers.
[16:25:34.654] INFO: data taking finished, elapsed time: 100 seconds.
[16:25:34.850] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:25:38.441] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 156030 events.
[16:25:39.230] DEBUG: <PixTestXray.cc/processData:L823> # events read: 156030, pixels seen in all events: 725791
[16:25:39.290] INFO: PixTest:: pg_setup set to default.
[16:25:39.331] INFO: PixTestXray::doPhRun() done
[16:25:39.331] INFO: PixTestXray::doTest() done
[16:27:45.105] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:27:45.106] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 378.6mA
[16:27:45.107] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[16:27:45.107] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[16:27:45.107] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:27:45.107] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:27:45.107] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:27:45.107] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:27:45.107] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:27:45.107] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:27:45.107] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:27:45.107] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:27:45.107] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:27:45.107] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:27:45.107] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:27:45.107] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:27:45.107] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:27:45.108] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:27:45.108] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:27:45.108] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:27:45.108] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:27:45.108] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:27:45.398] QUIET: Connection to board 58 closed.
[16:27:45.478] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[16:27:59.229] INFO: *** Welcome to pxar ***
[16:27:59.229] INFO: *** Today: 2016/10/14
[16:27:59.254] INFO: *** Version: v1.9.0-818-g96727
[16:27:59.255] INFO: readRocDacs: data/mq839/dacParameters35_C0.dat .. data/mq839/dacParameters35_C15.dat
[16:27:59.256] INFO: readTbmDacs: data/mq839/tbmParameters_C0a.dat .. data/mq839/tbmParameters_C0b.dat
[16:27:59.256] INFO: readMaskFile: data/mq839/defaultMaskFile.dat
[16:27:59.256] INFO: readTrimFile: data/mq839/trimParameters35_C0.dat .. data/mq839/trimParameters35_C15.dat
[16:27:59.409] INFO: clk: 4
[16:27:59.409] INFO: ctr: 4
[16:27:59.409] INFO: sda: 19
[16:27:59.409] INFO: tin: 9
[16:27:59.409] INFO: level: 15
[16:27:59.409] INFO: triggerdelay: 0
[16:27:59.409] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:27:59.409] INFO: Log level: DEBUG
[16:27:59.420] QUIET: Connection to board DTB_WRPRHI opened.
[16:27:59.423] 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:
------------------------------------------------------
[16:27:59.426] INFO: RPC call hashes of host and DTB match: 398089610
[16:28:01.029] INFO: DUT info:
[16:28:01.029] INFO: The DUT currently contains the following objects:
[16:28:01.029] INFO: 2 TBM Cores tbm08c (2 ON)
[16:28:01.029] INFO: TBM Core alpha (0): 7 registers set
[16:28:01.029] INFO: TBM Core beta (1): 7 registers set
[16:28:01.029] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:28:01.029] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.029] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.029] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.029] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.029] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.029] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.029] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.029] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.029] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.029] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.029] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.029] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.029] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.029] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.029] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.030] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:28:01.030] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:28:01.031] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:28:01.032] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:28:01.033] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:28:01.035] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31342592
[16:28:01.035] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1b76170
[16:28:01.035] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1b04370
[16:28:01.035] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f3285d94010
[16:28:01.035] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f328bfff510
[16:28:01.035] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31350784 fPxarMemory = 0x7f3285d94010
[16:28:01.036] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 374.6mA
[16:28:01.038] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[16:28:01.038] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:28:01.532] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2151030
[16:28:01.599] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:28:01.599] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:28:01.599] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:28:01.605] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:28:01.605] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:28:01.605] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:28:01.618] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:28:01.618] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:28:01.628] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:28:01.628] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:28:01.651] INFO: PixTestCmd::init()
[16:28:01.662] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:28:01.662] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:28:01.662] INFO: readGainPedestalParameters data/mq839/phCalibrationFitErr35_C0.dat .. data/mq839/phCalibrationFitErr35_C15.dat
[16:28:01.916] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:28:01.916] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:28:01.931] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:28:01.938] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:28:01.938] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:28:01.938] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:28:01.961] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:28:01.961] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:28:01.961] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:28:01.961] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:28:01.961] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:28:01.968] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:28:01.968] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:28:01.991] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:28:01.991] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:28:01.991] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:28:01.991] INFO: readReadbackCal: data/mq839/readbackCal_C0.dat .. data/mq839/readbackCal_C15.dat
[16:28:02.000] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:28:02.012] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:28:02.012] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:28:02.012] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:28:02.012] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:28:02.030] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:28:02.030] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:28:04.433] INFO: ######################################################################
[16:28:04.433] INFO: PixTestXray::doTest()
[16:28:04.433] INFO: ######################################################################
[16:28:04.433] INFO: ----------------------------------------------------------------------
[16:28:04.433] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:28:04.433] INFO: ----------------------------------------------------------------------
[16:28:05.407] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:28:12.805] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[16:28:12.809] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:28:38.103] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 729001 events.
[16:28:50.403] DEBUG: <PixTestXray.cc/processData:L823> # events read: 729001, pixels seen in all events: 11505372
[16:28:50.634] INFO: Resuming triggers.
[16:28:58.047] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[16:28:58.091] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:29:23.504] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 730293 events.
[16:29:35.776] DEBUG: <PixTestXray.cc/processData:L823> # events read: 730293, pixels seen in all events: 11489072
[16:29:35.965] INFO: Resuming triggers.
[16:29:43.375] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[16:29:43.441] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:30:08.815] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 729988 events.
[16:30:21.144] DEBUG: <PixTestXray.cc/processData:L823> # events read: 729988, pixels seen in all events: 11491826
[16:30:21.361] INFO: Resuming triggers.
[16:30:28.777] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[16:30:28.821] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:30:54.051] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 730480 events.
[16:31:06.327] DEBUG: <PixTestXray.cc/processData:L823> # events read: 730480, pixels seen in all events: 11485946
[16:31:06.537] INFO: Resuming triggers.
[16:31:13.953] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[16:31:13.995] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:31:39.253] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 730541 events.
[16:31:51.586] DEBUG: <PixTestXray.cc/processData:L823> # events read: 730541, pixels seen in all events: 11485821
[16:31:51.801] INFO: Resuming triggers.
[16:31:59.216] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[16:31:59.259] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:32:24.457] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 730398 events.
[16:32:36.741] DEBUG: <PixTestXray.cc/processData:L823> # events read: 730398, pixels seen in all events: 11487399
[16:32:36.958] INFO: Resuming triggers.
[16:32:44.379] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[16:32:44.422] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:33:09.731] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 731020 events.
[16:33:22.051] DEBUG: <PixTestXray.cc/processData:L823> # events read: 731020, pixels seen in all events: 11479192
[16:33:22.243] INFO: Resuming triggers.
[16:33:29.663] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[16:33:29.720] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:33:55.008] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 731009 events.
[16:34:07.299] DEBUG: <PixTestXray.cc/processData:L823> # events read: 731009, pixels seen in all events: 11480272
[16:34:07.510] INFO: Resuming triggers.
[16:34:14.927] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[16:34:14.969] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:34:40.152] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 730628 events.
[16:34:52.462] DEBUG: <PixTestXray.cc/processData:L823> # events read: 730628, pixels seen in all events: 11484431
[16:34:52.674] INFO: Resuming triggers.
[16:35:00.090] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[16:35:00.130] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:35:25.448] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 730528 events.
[16:35:37.727] DEBUG: <PixTestXray.cc/processData:L823> # events read: 730528, pixels seen in all events: 11485811
[16:35:37.939] INFO: Resuming triggers.
[16:35:45.360] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[16:35:45.400] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:36:10.717] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 731033 events.
[16:36:23.007] DEBUG: <PixTestXray.cc/processData:L823> # events read: 731033, pixels seen in all events: 11479942
[16:36:23.219] INFO: Resuming triggers.
[16:36:30.637] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[16:36:30.680] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:36:55.915] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 730798 events.
[16:37:08.199] DEBUG: <PixTestXray.cc/processData:L823> # events read: 730798, pixels seen in all events: 11482766
[16:37:08.415] INFO: Resuming triggers.
[16:37:15.835] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[16:37:15.879] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:37:41.085] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 730901 events.
[16:37:53.358] DEBUG: <PixTestXray.cc/processData:L823> # events read: 730901, pixels seen in all events: 11480888
[16:37:53.572] INFO: Resuming triggers.
[16:37:57.310] INFO: data taking finished, elapsed time: 100 seconds.
[16:37:57.506] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:38:10.354] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 368194 events.
[16:38:16.562] DEBUG: <PixTestXray.cc/processData:L823> # events read: 368194, pixels seen in all events: 5781920
[16:38:16.664] INFO: PixTest:: pg_setup set to default.
[16:38:16.710] INFO: PixTestXray::doPhRun() done
[16:38:16.710] INFO: PixTestXray::doTest() done
[16:45:15.265] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:45:15.266] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 379.4mA
[16:45:15.267] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[16:45:15.267] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[16:45:15.267] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:45:15.267] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:45:15.267] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:45:15.267] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:45:15.267] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:45:15.267] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:45:15.268] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:45:15.268] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:45:15.268] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:45:15.268] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:45:15.268] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:45:15.268] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:45:15.268] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:45:15.268] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:45:15.268] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:45:15.268] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:45:15.268] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:45:15.268] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:45:15.590] QUIET: Connection to board 58 closed.
[16:45:15.669] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[12:24:39.756] INFO: *** Welcome to pxar ***
[12:24:39.756] INFO: *** Today: 2016/10/14
[12:24:39.780] INFO: *** Version: v1.9.0-818-g96727
[12:24:39.781] INFO: readRocDacs: data/mq839/dacParameters35_C0.dat .. data/mq839/dacParameters35_C15.dat
[12:24:39.781] INFO: readTbmDacs: data/mq839/tbmParameters_C0a.dat .. data/mq839/tbmParameters_C0b.dat
[12:24:39.782] INFO: readMaskFile: data/mq839/defaultMaskFile.dat
[12:24:39.782] INFO: readTrimFile: data/mq839/trimParameters35_C0.dat .. data/mq839/trimParameters35_C15.dat
[12:24:39.935] INFO: clk: 4
[12:24:39.935] INFO: ctr: 4
[12:24:39.935] INFO: sda: 19
[12:24:39.935] INFO: tin: 9
[12:24:39.935] INFO: level: 15
[12:24:39.935] INFO: triggerdelay: 0
[12:24:39.935] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:24:39.935] INFO: Log level: DEBUG
[12:24:39.946] QUIET: Connection to board DTB_WRPRHI opened.
[12:24:39.949] 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:
------------------------------------------------------
[12:24:39.952] INFO: RPC call hashes of host and DTB match: 398089610
[12:24:41.555] INFO: DUT info:
[12:24:41.556] INFO: The DUT currently contains the following objects:
[12:24:41.556] INFO: 2 TBM Cores tbm08c (2 ON)
[12:24:41.556] INFO: TBM Core alpha (0): 7 registers set
[12:24:41.556] INFO: TBM Core beta (1): 7 registers set
[12:24:41.556] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:24:41.556] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:41.556] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:24:41.557] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:24:41.558] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:24:41.559] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:24:41.560] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:24:41.560] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:24:41.560] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:24:41.560] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:24:41.560] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:24:41.562] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30330880
[12:24:41.562] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1d9c0e0
[12:24:41.562] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1b3e370
[12:24:41.562] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f8d21d94010
[12:24:41.562] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f8d27fff510
[12:24:41.562] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30339072 fPxarMemory = 0x7f8d21d94010
[12:24:41.563] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[12:24:41.564] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[12:24:41.564] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[12:24:42.065] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x218afd0
[12:24:42.131] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:24:42.131] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:24:42.131] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:24:42.137] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:24:42.137] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:24:42.137] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:24:42.147] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:24:42.147] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:24:42.167] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:24:42.167] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:24:42.180] INFO: PixTestCmd::init()
[12:24:42.196] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:24:42.196] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:24:42.196] INFO: readGainPedestalParameters data/mq839/phCalibrationFitErr35_C0.dat .. data/mq839/phCalibrationFitErr35_C15.dat
[12:24:42.444] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:24:42.444] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:24:42.463] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:24:42.471] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:24:42.471] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:24:42.471] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:24:42.500] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:24:42.500] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:24:42.500] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:24:42.500] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:24:42.500] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:24:42.508] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:24:42.508] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:24:42.532] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:24:42.532] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:24:42.532] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:24:42.532] INFO: readReadbackCal: data/mq839/readbackCal_C0.dat .. data/mq839/readbackCal_C15.dat
[12:24:42.549] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:24:42.557] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:24:42.557] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:24:42.557] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:24:42.557] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:24:42.576] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:24:42.576] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:24:53.008] INFO: ######################################################################
[12:24:53.009] INFO: PixTestAlive::doTest()
[12:24:53.009] INFO: ######################################################################
[12:24:53.012] INFO: ----------------------------------------------------------------------
[12:24:53.012] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:24:53.012] INFO: ----------------------------------------------------------------------
[12:24:53.014] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:53.394] INFO: Expecting 41600 events.
[12:24:57.799] INFO: 41600 events read in total (3687ms).
[12:24:57.945] INFO: Test took 4931ms.
[12:24:57.957] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:24:57.957] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[12:24:57.957] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[12:24:58.227] INFO: PixTestAlive::aliveTest() done
[12:24:58.228] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:24:58.228] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 17 9 21 29 39 29 41 45 41 42 45 49 42 26 12 21
[12:24:58.257] INFO: ----------------------------------------------------------------------
[12:24:58.257] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:24:58.257] INFO: ----------------------------------------------------------------------
[12:24:58.259] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:24:58.636] INFO: Expecting 41600 events.
[12:25:01.751] INFO: 41600 events read in total (2399ms).
[12:25:01.752] INFO: Test took 3493ms.
[12:25:01.752] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:01.752] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[12:25:01.752] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[12:25:01.752] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:25:02.174] INFO: PixTestAlive::maskTest() done
[12:25:02.174] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:25:02.197] INFO: ----------------------------------------------------------------------
[12:25:02.197] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:25:02.197] INFO: ----------------------------------------------------------------------
[12:25:02.199] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:02.576] INFO: Expecting 41600 events.
[12:25:06.956] INFO: 41600 events read in total (3664ms).
[12:25:06.958] INFO: Test took 4759ms.
[12:25:06.968] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:06.968] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[12:25:06.968] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[12:25:07.338] INFO: PixTestAlive::addressDecodingTest() done
[12:25:07.338] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:25:07.338] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[12:25:14.773] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:25:14.773] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C3_V0 -> PixelAlive_mod
[12:25:18.437] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[12:25:18.437] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[12:25:18.437] INFO: ----------------------------------------------------------------------
[12:25:18.437] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:25:18.437] INFO: ----------------------------------------------------------------------
[12:25:18.576] INFO: Expecting 768 events.
[12:25:19.709] INFO: 768 events read in total (417ms).
[12:25:19.710] INFO: Test took 1267ms.
[12:25:19.715] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:20.513] INFO: Expecting 41600 events.
[12:25:23.894] INFO: 41600 events read in total (2854ms).
[12:25:23.898] INFO: Test took 4182ms.
[12:25:23.980] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:23.980] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 467906
[12:25:23.980] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[12:25:23.981] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:24.019] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:24.685] INFO: Expecting 41600 events.
[12:25:28.168] INFO: 41600 events read in total (2956ms).
[12:25:28.172] INFO: Test took 4153ms.
[12:25:28.251] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:28.251] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476370
[12:25:28.251] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[12:25:28.251] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:28.291] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:28.956] INFO: Expecting 41600 events.
[12:25:32.460] INFO: 41600 events read in total (2977ms).
[12:25:32.463] INFO: Test took 4172ms.
[12:25:32.542] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:32.542] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477099
[12:25:32.542] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[12:25:32.542] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:32.582] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:33.247] INFO: Expecting 41600 events.
[12:25:36.761] INFO: 41600 events read in total (2987ms).
[12:25:36.765] INFO: Test took 4183ms.
[12:25:36.844] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:36.844] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 475651
[12:25:36.844] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[12:25:36.845] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:36.884] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:37.550] INFO: Expecting 41600 events.
[12:25:41.051] INFO: 41600 events read in total (2975ms).
[12:25:41.055] INFO: Test took 4171ms.
[12:25:41.133] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:41.133] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476607
[12:25:41.133] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[12:25:41.133] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:41.172] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:41.838] INFO: Expecting 41600 events.
[12:25:45.339] INFO: 41600 events read in total (2974ms).
[12:25:45.343] INFO: Test took 4171ms.
[12:25:45.422] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:45.422] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477663
[12:25:45.422] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[12:25:45.422] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:45.462] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:46.127] INFO: Expecting 41600 events.
[12:25:49.601] INFO: 41600 events read in total (2947ms).
[12:25:49.605] INFO: Test took 4143ms.
[12:25:49.683] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:49.683] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477060
[12:25:49.683] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[12:25:49.683] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:49.723] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:50.389] INFO: Expecting 41600 events.
[12:25:53.881] INFO: 41600 events read in total (2965ms).
[12:25:53.885] INFO: Test took 4162ms.
[12:25:53.965] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:53.965] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477769
[12:25:53.965] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[12:25:53.965] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:54.005] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:54.671] INFO: Expecting 41600 events.
[12:25:58.170] INFO: 41600 events read in total (2973ms).
[12:25:58.174] INFO: Test took 4169ms.
[12:25:58.252] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:25:58.252] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 475739
[12:25:58.252] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[12:25:58.253] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:25:58.292] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:25:58.957] INFO: Expecting 41600 events.
[12:26:02.458] INFO: 41600 events read in total (2974ms).
[12:26:02.461] INFO: Test took 4169ms.
[12:26:02.540] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:02.540] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476466
[12:26:02.540] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[12:26:02.540] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:26:02.580] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:26:03.245] INFO: Expecting 41600 events.
[12:26:06.749] INFO: 41600 events read in total (2978ms).
[12:26:06.752] INFO: Test took 4172ms.
[12:26:06.832] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:06.832] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476929
[12:26:06.832] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[12:26:06.832] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:26:06.872] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:26:07.537] INFO: Expecting 41600 events.
[12:26:11.026] INFO: 41600 events read in total (2963ms).
[12:26:11.030] INFO: Test took 4158ms.
[12:26:11.109] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:11.109] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476926
[12:26:11.109] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[12:26:11.109] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:26:11.149] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:26:11.814] INFO: Expecting 41600 events.
[12:26:15.323] INFO: 41600 events read in total (2982ms).
[12:26:15.326] INFO: Test took 4177ms.
[12:26:15.405] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:15.405] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476689
[12:26:15.405] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[12:26:15.406] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:26:15.446] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:26:16.111] INFO: Expecting 41600 events.
[12:26:19.623] INFO: 41600 events read in total (2985ms).
[12:26:19.627] INFO: Test took 4181ms.
[12:26:19.708] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:19.708] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477431
[12:26:19.709] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[12:26:19.709] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:26:19.749] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:26:20.414] INFO: Expecting 41600 events.
[12:26:23.927] INFO: 41600 events read in total (2986ms).
[12:26:23.930] INFO: Test took 4181ms.
[12:26:24.008] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:24.008] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 475648
[12:26:24.008] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[12:26:24.008] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:26:24.048] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:26:24.713] INFO: Expecting 41600 events.
[12:26:28.192] INFO: 41600 events read in total (2952ms).
[12:26:28.196] INFO: Test took 4148ms.
[12:26:28.273] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:28.273] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476322
[12:26:28.273] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[12:26:28.274] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:26:28.314] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:26:28.979] INFO: Expecting 41600 events.
[12:26:32.486] INFO: 41600 events read in total (2981ms).
[12:26:32.490] INFO: Test took 4176ms.
[12:26:32.569] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:32.569] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477364
[12:26:32.569] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[12:26:32.569] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:26:32.609] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:26:33.275] INFO: Expecting 41600 events.
[12:26:36.786] INFO: 41600 events read in total (2985ms).
[12:26:36.790] INFO: Test took 4181ms.
[12:26:36.868] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:36.869] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476094
[12:26:36.869] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[12:26:36.869] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:26:36.909] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:26:37.575] INFO: Expecting 41600 events.
[12:26:41.076] INFO: 41600 events read in total (2975ms).
[12:26:41.080] INFO: Test took 4171ms.
[12:26:41.159] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:41.159] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476602
[12:26:41.159] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[12:26:41.159] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:26:41.199] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:26:41.864] INFO: Expecting 41600 events.
[12:26:45.214] INFO: 41600 events read in total (2823ms).
[12:26:45.217] INFO: Test took 4018ms.
[12:26:45.299] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:26:45.299] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 470399
[12:26:45.299] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[12:26:45.300] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:26:45.562] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 149 eff = 0.999639
[12:26:45.562] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 149 eff = 0.99976
[12:26:45.562] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 145 eff = 0.999351
[12:26:45.562] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 162 eff = 0.999014
[12:26:45.562] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 143 eff = 0.998966
[12:26:45.563] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 174 eff = 0.999038
[12:26:45.563] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 159 eff = 0.998726
[12:26:45.563] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 174 eff = 0.998822
[12:26:45.563] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 150 eff = 0.999111
[12:26:45.563] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 168 eff = 0.998798
[12:26:45.563] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 187 eff = 0.998894
[12:26:45.563] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 162 eff = 0.999159
[12:26:45.564] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 163 eff = 0.998894
[12:26:45.564] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 175 eff = 0.999519
[12:26:45.564] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 166 eff = 0.999712
[12:26:45.564] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 142 eff = 0.999784
[12:26:50.413] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[12:26:50.413] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[12:26:50.414] INFO: ----------------------------------------------------------------------
[12:26:50.414] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:26:50.414] INFO: ----------------------------------------------------------------------
[12:26:50.414] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[12:26:50.414] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[12:26:50.414] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[12:26:50.414] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[12:26:50.414] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[12:26:50.414] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[12:26:50.414] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[12:26:50.414] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[12:26:50.414] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[12:26:50.415] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[12:26:50.415] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[12:26:50.415] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[12:26:50.434] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:26:51.105] INFO: Expecting 208000 events.
[12:27:04.691] INFO: 208000 events read in total (13060ms).
[12:27:04.703] INFO: Test took 14269ms.
[12:27:05.084] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:05.084] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2100621
[12:27:05.084] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[12:27:05.084] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:27:05.458] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:27:05.458] INFO: number of red-efficiency pixels: 152 99 163 192 277 191 262 240 234 333 228 222 258 126 47 74
[12:27:05.458] INFO: number of X-ray hits detected: 91475 59698 97629 142681 155209 156969 161880 151399 147669 169418 166300 150286 152416 100461 58355 72216
[12:27:05.458] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:27:05.458] INFO: number of Vcal hits detected: 207844 207898 207831 207806 207711 207798 207733 207753 207755 207644 207766 207771 207735 207871 207952 207923
[12:27:05.458] 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.9 99.9 99.9 99.9 100.0 100.0
[12:27:05.458] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[12:27:05.458] INFO: X-ray hit rate [MHz/cm2]: 26.8 17.5 28.6 41.8 45.5 46.0 47.4 44.4 43.3 49.7 48.7 44.0 44.7 29.4 17.1 21.2
[12:27:05.458] INFO: PixTestHighRate::doXPixelAlive() done
[12:27:05.505] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[12:27:05.506] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[12:27:05.506] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[12:27:05.506] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[12:27:05.506] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[12:27:05.506] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[12:27:05.506] INFO: PixTest:: pg_setup set to default.
[12:27:11.989] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:27:11.989] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C13_V0 -> highRate_xraymap_mod
[12:27:25.317] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:27:25.318] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.1mA
[12:27:25.319] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 469.5mA
[12:27:25.319] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.5 C
[12:27:25.319] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:27:25.319] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:27:25.319] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:27:25.319] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:27:25.319] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:27:25.319] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:27:25.319] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:27:25.320] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:27:25.320] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:27:25.320] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:27:25.407] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:27:25.407] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:27:25.421] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:27:25.421] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:27:25.421] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:27:25.421] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:27:25.421] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:27:25.421] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:27:25.424] QUIET: Connection to board 58 closed.
[12:27:25.503] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[12:21:03.172] INFO: *** Welcome to pxar ***
[12:21:03.172] INFO: *** Today: 2016/10/14
[12:21:03.197] INFO: *** Version: v1.9.0-818-g96727
[12:21:03.198] INFO: readRocDacs: data/mq839/dacParameters35_C0.dat .. data/mq839/dacParameters35_C15.dat
[12:21:03.198] INFO: readTbmDacs: data/mq839/tbmParameters_C0a.dat .. data/mq839/tbmParameters_C0b.dat
[12:21:03.199] INFO: readMaskFile: data/mq839/defaultMaskFile.dat
[12:21:03.199] INFO: readTrimFile: data/mq839/trimParameters35_C0.dat .. data/mq839/trimParameters35_C15.dat
[12:21:03.347] INFO: clk: 4
[12:21:03.347] INFO: ctr: 4
[12:21:03.347] INFO: sda: 19
[12:21:03.347] INFO: tin: 9
[12:21:03.347] INFO: level: 15
[12:21:03.347] INFO: triggerdelay: 0
[12:21:03.347] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:21:03.347] INFO: Log level: DEBUG
[12:21:03.358] QUIET: Connection to board DTB_WRPRHI opened.
[12:21:03.361] 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:
------------------------------------------------------
[12:21:03.364] INFO: RPC call hashes of host and DTB match: 398089610
[12:21:04.967] INFO: DUT info:
[12:21:04.967] INFO: The DUT currently contains the following objects:
[12:21:04.967] INFO: 2 TBM Cores tbm08c (2 ON)
[12:21:04.968] INFO: TBM Core alpha (0): 7 registers set
[12:21:04.968] INFO: TBM Core beta (1): 7 registers set
[12:21:04.968] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:21:04.968] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:21:04.968] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:21:04.968] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:21:04.968] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:21:04.968] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:21:04.969] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:21:04.970] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:21:04.971] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:21:04.974] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30334976
[12:21:04.974] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x23e30e0
[12:21:04.974] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2185370
[12:21:04.974] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f5579d94010
[12:21:04.974] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f557ffff510
[12:21:04.974] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30343168 fPxarMemory = 0x7f5579d94010
[12:21:04.975] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[12:21:04.976] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 469.5mA
[12:21:04.976] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[12:21:05.471] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x27d2090
[12:21:05.536] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:21:05.536] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:21:05.536] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:21:05.542] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:21:05.542] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:21:05.542] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:21:05.552] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:21:05.552] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:21:05.574] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:21:05.574] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:21:05.596] INFO: PixTestCmd::init()
[12:21:05.607] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:21:05.607] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:21:05.608] INFO: readGainPedestalParameters data/mq839/phCalibrationFitErr35_C0.dat .. data/mq839/phCalibrationFitErr35_C15.dat
[12:21:05.855] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:21:05.855] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:21:05.869] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:21:05.875] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:21:05.875] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:21:05.875] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:21:05.900] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:21:05.900] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:21:05.900] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:21:05.900] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:21:05.900] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:21:05.907] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:21:05.907] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:21:05.928] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:21:05.928] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:21:05.928] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:21:05.928] INFO: readReadbackCal: data/mq839/readbackCal_C0.dat .. data/mq839/readbackCal_C15.dat
[12:21:05.937] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:21:05.945] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:21:05.946] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:21:05.946] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:21:05.946] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:21:05.966] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:21:05.966] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:21:11.505] INFO: ######################################################################
[12:21:11.505] INFO: PixTestAlive::doTest()
[12:21:11.505] INFO: ######################################################################
[12:21:11.508] INFO: ----------------------------------------------------------------------
[12:21:11.508] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:21:11.508] INFO: ----------------------------------------------------------------------
[12:21:11.510] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:11.889] INFO: Expecting 41600 events.
[12:21:16.292] INFO: 41600 events read in total (3684ms).
[12:21:16.441] INFO: Test took 4931ms.
[12:21:16.452] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:16.452] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[12:21:16.452] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[12:21:16.732] INFO: PixTestAlive::aliveTest() done
[12:21:16.732] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:21:16.732] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 46 23 39 45 55 38 75 65 72 94 46 58 45 39 23 23
[12:21:16.759] INFO: ----------------------------------------------------------------------
[12:21:16.759] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:21:16.759] INFO: ----------------------------------------------------------------------
[12:21:16.761] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:17.138] INFO: Expecting 41600 events.
[12:21:20.264] INFO: 41600 events read in total (2410ms).
[12:21:20.265] INFO: Test took 3504ms.
[12:21:20.265] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:20.265] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[12:21:20.265] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[12:21:20.265] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:21:20.686] INFO: PixTestAlive::maskTest() done
[12:21:20.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
[12:21:20.708] INFO: ----------------------------------------------------------------------
[12:21:20.708] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:21:20.708] INFO: ----------------------------------------------------------------------
[12:21:20.711] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:21.088] INFO: Expecting 41600 events.
[12:21:25.484] INFO: 41600 events read in total (3681ms).
[12:21:25.485] INFO: Test took 4774ms.
[12:21:25.497] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:25.497] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[12:21:25.497] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[12:21:25.867] INFO: PixTestAlive::addressDecodingTest() done
[12:21:25.867] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:21:25.867] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[12:21:33.029] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:21:33.029] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C1_V0 -> PixelAlive_mod
[12:21:40.381] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[12:21:40.381] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[12:21:40.381] INFO: ----------------------------------------------------------------------
[12:21:40.381] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:21:40.381] INFO: ----------------------------------------------------------------------
[12:21:40.521] INFO: Expecting 768 events.
[12:21:41.655] INFO: 768 events read in total (419ms).
[12:21:41.655] INFO: Test took 1268ms.
[12:21:41.661] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:42.458] INFO: Expecting 41600 events.
[12:21:46.062] INFO: 41600 events read in total (3077ms).
[12:21:46.066] INFO: Test took 4405ms.
[12:21:46.188] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:46.188] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 677359
[12:21:46.188] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[12:21:46.188] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:46.241] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:46.908] INFO: Expecting 41600 events.
[12:21:50.634] INFO: 41600 events read in total (3199ms).
[12:21:50.638] INFO: Test took 4397ms.
[12:21:50.754] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:50.754] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683377
[12:21:50.754] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[12:21:50.755] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:50.810] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:51.477] INFO: Expecting 41600 events.
[12:21:55.226] INFO: 41600 events read in total (3222ms).
[12:21:55.231] INFO: Test took 4421ms.
[12:21:55.348] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:55.348] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 686642
[12:21:55.348] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[12:21:55.348] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:21:55.404] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:21:56.071] INFO: Expecting 41600 events.
[12:21:59.840] INFO: 41600 events read in total (3242ms).
[12:21:59.844] INFO: Test took 4440ms.
[12:21:59.961] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:59.961] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 686912
[12:21:59.961] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[12:21:59.961] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:00.015] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:00.681] INFO: Expecting 41600 events.
[12:22:04.421] INFO: 41600 events read in total (3214ms).
[12:22:04.426] INFO: Test took 4411ms.
[12:22:04.543] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:04.543] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688133
[12:22:04.543] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[12:22:04.543] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:04.599] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:05.267] INFO: Expecting 41600 events.
[12:22:09.041] INFO: 41600 events read in total (3247ms).
[12:22:09.045] INFO: Test took 4446ms.
[12:22:09.163] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:09.163] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688014
[12:22:09.163] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[12:22:09.163] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:09.218] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:09.886] INFO: Expecting 41600 events.
[12:22:13.645] INFO: 41600 events read in total (3233ms).
[12:22:13.650] INFO: Test took 4432ms.
[12:22:13.771] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:13.771] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688130
[12:22:13.772] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[12:22:13.772] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:13.827] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:14.494] INFO: Expecting 41600 events.
[12:22:18.287] INFO: 41600 events read in total (3266ms).
[12:22:18.292] INFO: Test took 4465ms.
[12:22:18.410] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:18.411] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 686093
[12:22:18.411] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[12:22:18.411] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:18.466] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:19.133] INFO: Expecting 41600 events.
[12:22:22.921] INFO: 41600 events read in total (3261ms).
[12:22:22.925] INFO: Test took 4459ms.
[12:22:23.042] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:23.042] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688441
[12:22:23.042] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[12:22:23.042] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:23.096] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:23.761] INFO: Expecting 41600 events.
[12:22:27.537] INFO: 41600 events read in total (3249ms).
[12:22:27.541] INFO: Test took 4445ms.
[12:22:27.657] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:27.657] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688308
[12:22:27.657] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[12:22:27.657] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:27.711] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:28.376] INFO: Expecting 41600 events.
[12:22:32.160] INFO: 41600 events read in total (3257ms).
[12:22:32.165] INFO: Test took 4454ms.
[12:22:32.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:32.283] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 686213
[12:22:32.284] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[12:22:32.284] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:32.338] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:33.002] INFO: Expecting 41600 events.
[12:22:36.776] INFO: 41600 events read in total (3247ms).
[12:22:36.781] INFO: Test took 4443ms.
[12:22:36.941] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:36.941] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 687297
[12:22:36.941] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[12:22:36.941] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:36.997] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:37.664] INFO: Expecting 41600 events.
[12:22:41.442] INFO: 41600 events read in total (3251ms).
[12:22:41.447] INFO: Test took 4450ms.
[12:22:41.564] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:41.564] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 687691
[12:22:41.564] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[12:22:41.564] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:41.618] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:42.284] INFO: Expecting 41600 events.
[12:22:46.064] INFO: 41600 events read in total (3254ms).
[12:22:46.069] INFO: Test took 4451ms.
[12:22:46.184] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:46.184] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 687188
[12:22:46.184] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[12:22:46.184] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:46.238] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:46.904] INFO: Expecting 41600 events.
[12:22:50.683] INFO: 41600 events read in total (3253ms).
[12:22:50.687] INFO: Test took 4449ms.
[12:22:50.804] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:50.804] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 687471
[12:22:50.804] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[12:22:50.804] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:50.858] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:51.523] INFO: Expecting 41600 events.
[12:22:55.312] INFO: 41600 events read in total (3262ms).
[12:22:55.317] INFO: Test took 4459ms.
[12:22:55.432] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:22:55.432] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688304
[12:22:55.432] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[12:22:55.433] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:22:55.486] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:22:56.151] INFO: Expecting 41600 events.
[12:22:59.920] INFO: 41600 events read in total (3242ms).
[12:22:59.925] INFO: Test took 4439ms.
[12:23:00.042] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:00.042] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688367
[12:23:00.042] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[12:23:00.043] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:23:00.097] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:23:00.762] INFO: Expecting 41600 events.
[12:23:04.533] INFO: 41600 events read in total (3244ms).
[12:23:04.538] INFO: Test took 4441ms.
[12:23:04.655] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:04.655] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 686522
[12:23:04.655] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[12:23:04.655] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:23:04.710] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:23:05.376] INFO: Expecting 41600 events.
[12:23:09.150] INFO: 41600 events read in total (3248ms).
[12:23:09.155] INFO: Test took 4445ms.
[12:23:09.272] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:09.272] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 686231
[12:23:09.272] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[12:23:09.273] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:23:09.328] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:23:09.995] INFO: Expecting 41600 events.
[12:23:13.600] INFO: 41600 events read in total (3078ms).
[12:23:13.604] INFO: Test took 4275ms.
[12:23:13.716] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:13.716] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 679911
[12:23:13.716] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[12:23:13.716] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:23:13.991] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 167 eff = 0.999279
[12:23:13.991] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 163 eff = 0.999447
[12:23:13.991] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 164 eff = 0.998654
[12:23:13.991] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 162 eff = 0.998197
[12:23:13.992] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 161 eff = 0.997837
[12:23:13.992] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 169 eff = 0.997813
[12:23:13.992] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 160 eff = 0.99762
[12:23:13.992] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 174 eff = 0.997837
[12:23:13.993] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 155 eff = 0.99851
[12:23:13.993] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 178 eff = 0.997428
[12:23:13.993] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 183 eff = 0.99738
[12:23:13.993] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 162 eff = 0.997788
[12:23:13.994] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 167 eff = 0.997788
[12:23:13.994] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 153 eff = 0.99863
[12:23:13.994] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 166 eff = 0.999615
[12:23:13.994] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 161 eff = 0.999447
[12:23:17.477] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[12:23:17.477] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[12:23:17.477] INFO: ----------------------------------------------------------------------
[12:23:17.477] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:23:17.477] INFO: ----------------------------------------------------------------------
[12:23:17.478] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[12:23:17.478] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[12:23:17.478] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[12:23:17.478] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[12:23:17.478] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[12:23:17.478] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[12:23:17.478] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[12:23:17.478] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[12:23:17.478] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[12:23:17.478] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[12:23:17.478] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[12:23:17.478] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[12:23:17.495] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:23:18.162] INFO: Expecting 208000 events.
[12:23:33.416] INFO: 208000 events read in total (14727ms).
[12:23:33.432] INFO: Test took 15937ms.
[12:23:34.029] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:34.029] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3141463
[12:23:34.029] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[12:23:34.030] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:23:34.472] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:23:34.472] INFO: number of red-efficiency pixels: 205 135 242 342 482 440 437 458 434 525 489 438 412 291 128 145
[12:23:34.472] INFO: number of X-ray hits detected: 136467 90723 148449 216093 233052 238224 246052 227809 221832 256509 250547 228632 230411 152422 88364 109317
[12:23:34.472] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:23:34.472] INFO: number of Vcal hits detected: 207786 207862 207734 207640 207479 207521 207522 207500 207535 207430 207470 207532 207565 207688 207870 207854
[12:23:34.472] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.9 99.9 99.9
[12:23:34.473] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.8 99.8 99.8 99.7 99.7 99.8 99.8 99.9 99.9 99.9
[12:23:34.473] INFO: X-ray hit rate [MHz/cm2]: 40.0 26.6 43.5 63.3 68.3 69.8 72.1 66.8 65.0 75.2 73.4 67.0 67.5 44.7 25.9 32.0
[12:23:34.473] INFO: PixTestHighRate::doXPixelAlive() done
[12:23:34.518] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[12:23:34.518] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[12:23:34.518] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[12:23:34.518] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[12:23:34.518] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[12:23:34.518] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[12:23:34.518] INFO: PixTest:: pg_setup set to default.
[12:23:53.333] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:23:53.333] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C13_V0 -> highRate_xraymap_mod
[12:24:01.717] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:24:01.718] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[12:24:01.719] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 469.5mA
[12:24:01.719] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[12:24:01.719] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:24:01.719] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:24:01.719] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:24:01.719] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:24:01.719] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:24:01.719] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:24:01.719] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:24:01.719] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:24:01.719] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:24:01.720] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:24:01.811] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:24:01.811] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:24:01.825] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:24:01.825] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:24:01.825] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:24:01.825] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:24:01.825] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:24:01.825] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:24:01.828] QUIET: Connection to board 58 closed.
[12:24:01.907] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[12:17:29.747] INFO: *** Welcome to pxar ***
[12:17:29.747] INFO: *** Today: 2016/10/14
[12:17:29.771] INFO: *** Version: v1.9.0-818-g96727
[12:17:29.771] INFO: readRocDacs: data/mq839/dacParameters35_C0.dat .. data/mq839/dacParameters35_C15.dat
[12:17:29.772] INFO: readTbmDacs: data/mq839/tbmParameters_C0a.dat .. data/mq839/tbmParameters_C0b.dat
[12:17:29.772] INFO: readMaskFile: data/mq839/defaultMaskFile.dat
[12:17:29.772] INFO: readTrimFile: data/mq839/trimParameters35_C0.dat .. data/mq839/trimParameters35_C15.dat
[12:17:29.920] INFO: clk: 4
[12:17:29.920] INFO: ctr: 4
[12:17:29.920] INFO: sda: 19
[12:17:29.920] INFO: tin: 9
[12:17:29.920] INFO: level: 15
[12:17:29.920] INFO: triggerdelay: 0
[12:17:29.920] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:17:29.920] INFO: Log level: DEBUG
[12:17:29.931] QUIET: Connection to board DTB_WRPRHI opened.
[12:17:29.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:
------------------------------------------------------
[12:17:29.937] INFO: RPC call hashes of host and DTB match: 398089610
[12:17:31.540] INFO: DUT info:
[12:17:31.540] INFO: The DUT currently contains the following objects:
[12:17:31.540] INFO: 2 TBM Cores tbm08c (2 ON)
[12:17:31.540] INFO: TBM Core alpha (0): 7 registers set
[12:17:31.540] INFO: TBM Core beta (1): 7 registers set
[12:17:31.540] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:17:31.540] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.540] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.540] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.540] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.540] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.540] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.540] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.540] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.540] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.540] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.540] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.540] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.540] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.540] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.540] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.541] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:17:31.541] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:17:31.542] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:17:31.543] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:17:31.544] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:17:31.546] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30334976
[12:17:31.546] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x206b0e0
[12:17:31.546] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1e0d370
[12:17:31.546] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f7355d94010
[12:17:31.546] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f735bfff510
[12:17:31.546] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30343168 fPxarMemory = 0x7f7355d94010
[12:17:31.548] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[12:17:31.549] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[12:17:31.549] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: 0.4 C
[12:17:32.041] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2459fd0
[12:17:32.104] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:17:32.104] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:17:32.105] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:17:32.111] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:17:32.111] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:17:32.111] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:17:32.122] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:17:32.122] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:17:32.137] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:17:32.138] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:17:32.164] INFO: PixTestCmd::init()
[12:17:32.175] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:17:32.176] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:17:32.176] INFO: readGainPedestalParameters data/mq839/phCalibrationFitErr35_C0.dat .. data/mq839/phCalibrationFitErr35_C15.dat
[12:17:32.422] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:17:32.422] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:17:32.438] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:17:32.444] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:17:32.444] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:17:32.445] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:17:32.474] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:17:32.474] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:17:32.474] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:17:32.474] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:17:32.474] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:17:32.480] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:17:32.480] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:17:32.503] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:17:32.503] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:17:32.503] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:17:32.503] INFO: readReadbackCal: data/mq839/readbackCal_C0.dat .. data/mq839/readbackCal_C15.dat
[12:17:32.516] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:17:32.531] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:17:32.531] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:17:32.531] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:17:32.531] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:17:32.547] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:17:32.547] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:17:47.048] INFO: ######################################################################
[12:17:47.049] INFO: PixTestAlive::doTest()
[12:17:47.049] INFO: ######################################################################
[12:17:47.052] INFO: ----------------------------------------------------------------------
[12:17:47.052] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:17:47.052] INFO: ----------------------------------------------------------------------
[12:17:47.054] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:17:47.435] INFO: Expecting 41600 events.
[12:17:51.824] INFO: 41600 events read in total (3671ms).
[12:17:51.971] INFO: Test took 4916ms.
[12:17:51.981] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:17:51.981] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[12:17:51.981] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[12:17:52.261] INFO: PixTestAlive::aliveTest() done
[12:17:52.261] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:17:52.261] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 58 38 48 66 77 73 69 93 78 79 86 73 100 48 33 31
[12:17:52.285] INFO: ----------------------------------------------------------------------
[12:17:52.285] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:17:52.285] INFO: ----------------------------------------------------------------------
[12:17:52.287] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:17:52.665] INFO: Expecting 41600 events.
[12:17:55.761] INFO: 41600 events read in total (2381ms).
[12:17:55.762] INFO: Test took 3475ms.
[12:17:55.762] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:17:55.762] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[12:17:55.762] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[12:17:55.763] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:17:56.184] INFO: PixTestAlive::maskTest() done
[12:17:56.184] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:17:56.207] INFO: ----------------------------------------------------------------------
[12:17:56.207] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:17:56.207] INFO: ----------------------------------------------------------------------
[12:17:56.209] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:17:56.587] INFO: Expecting 41600 events.
[12:18:00.970] INFO: 41600 events read in total (3668ms).
[12:18:00.971] INFO: Test took 4762ms.
[12:18:00.982] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:18:00.982] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[12:18:00.982] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[12:18:01.352] INFO: PixTestAlive::addressDecodingTest() done
[12:18:01.352] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:18:01.352] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[12:18:13.685] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:18:13.685] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[12:18:19.445] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[12:18:19.445] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[12:18:19.445] INFO: ----------------------------------------------------------------------
[12:18:19.445] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:18:19.445] INFO: ----------------------------------------------------------------------
[12:18:19.585] INFO: Expecting 768 events.
[12:18:20.719] INFO: 768 events read in total (419ms).
[12:18:20.719] INFO: Test took 1268ms.
[12:18:20.725] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:18:21.523] INFO: Expecting 41600 events.
[12:18:25.383] INFO: 41600 events read in total (3334ms).
[12:18:25.388] INFO: Test took 4663ms.
[12:18:25.540] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:18:25.540] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 873824
[12:18:25.540] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[12:18:25.540] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:18:25.604] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:18:26.269] INFO: Expecting 41600 events.
[12:18:30.224] INFO: 41600 events read in total (3428ms).
[12:18:30.230] INFO: Test took 4626ms.
[12:18:30.370] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:18:30.370] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 879273
[12:18:30.370] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[12:18:30.371] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:18:30.437] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:18:31.103] INFO: Expecting 41600 events.
[12:18:35.058] INFO: 41600 events read in total (3429ms).
[12:18:35.064] INFO: Test took 4627ms.
[12:18:35.207] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:18:35.207] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 882889
[12:18:35.207] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[12:18:35.208] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:18:35.274] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:18:35.940] INFO: Expecting 41600 events.
[12:18:39.899] INFO: 41600 events read in total (3433ms).
[12:18:39.905] INFO: Test took 4631ms.
[12:18:40.050] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:18:40.050] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 882491
[12:18:40.050] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[12:18:40.050] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:18:40.116] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:18:40.784] INFO: Expecting 41600 events.
[12:18:44.761] INFO: 41600 events read in total (3451ms).
[12:18:44.766] INFO: Test took 4650ms.
[12:18:44.910] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:18:44.910] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 882560
[12:18:44.911] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[12:18:44.911] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:18:44.977] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:18:45.642] INFO: Expecting 41600 events.
[12:18:49.624] INFO: 41600 events read in total (3455ms).
[12:18:49.629] INFO: Test took 4652ms.
[12:18:49.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:18:49.775] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 881228
[12:18:49.775] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[12:18:49.775] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:18:49.844] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:18:50.511] INFO: Expecting 41600 events.
[12:18:54.519] INFO: 41600 events read in total (3482ms).
[12:18:54.524] INFO: Test took 4680ms.
[12:18:54.668] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:18:54.668] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 882787
[12:18:54.668] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[12:18:54.668] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:18:54.735] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:18:55.400] INFO: Expecting 41600 events.
[12:18:59.406] INFO: 41600 events read in total (3480ms).
[12:18:59.411] INFO: Test took 4676ms.
[12:18:59.557] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:18:59.557] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 884396
[12:18:59.557] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[12:18:59.558] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:18:59.626] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:19:00.293] INFO: Expecting 41600 events.
[12:19:04.286] INFO: 41600 events read in total (3466ms).
[12:19:04.291] INFO: Test took 4665ms.
[12:19:04.439] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:04.439] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 883900
[12:19:04.439] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[12:19:04.439] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:19:04.505] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:19:05.170] INFO: Expecting 41600 events.
[12:19:09.174] INFO: 41600 events read in total (3477ms).
[12:19:09.179] INFO: Test took 4674ms.
[12:19:09.322] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:09.323] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 881938
[12:19:09.323] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[12:19:09.323] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:19:09.389] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:19:10.055] INFO: Expecting 41600 events.
[12:19:14.055] INFO: 41600 events read in total (3473ms).
[12:19:14.060] INFO: Test took 4670ms.
[12:19:14.205] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:14.205] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 885762
[12:19:14.205] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[12:19:14.205] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:19:14.272] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:19:14.937] INFO: Expecting 41600 events.
[12:19:18.921] INFO: 41600 events read in total (3457ms).
[12:19:18.926] INFO: Test took 4654ms.
[12:19:19.072] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:19.072] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 883868
[12:19:19.072] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[12:19:19.072] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:19:19.140] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:19:19.807] INFO: Expecting 41600 events.
[12:19:23.811] INFO: 41600 events read in total (3478ms).
[12:19:23.816] INFO: Test took 4676ms.
[12:19:23.962] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:23.962] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 881665
[12:19:23.962] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[12:19:23.963] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:19:24.027] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:19:24.692] INFO: Expecting 41600 events.
[12:19:28.680] INFO: 41600 events read in total (3461ms).
[12:19:28.685] INFO: Test took 4658ms.
[12:19:28.828] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:28.828] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 883774
[12:19:28.828] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[12:19:28.828] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:19:28.895] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:19:29.560] INFO: Expecting 41600 events.
[12:19:33.552] INFO: 41600 events read in total (3465ms).
[12:19:33.557] INFO: Test took 4662ms.
[12:19:33.702] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:33.702] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 882449
[12:19:33.702] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[12:19:33.702] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:19:33.771] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:19:34.438] INFO: Expecting 41600 events.
[12:19:38.435] INFO: 41600 events read in total (3471ms).
[12:19:38.440] INFO: Test took 4669ms.
[12:19:38.583] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:38.583] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 883709
[12:19:38.584] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[12:19:38.584] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:19:38.652] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:19:39.319] INFO: Expecting 41600 events.
[12:19:43.315] INFO: 41600 events read in total (3469ms).
[12:19:43.320] INFO: Test took 4668ms.
[12:19:43.463] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:43.463] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 882392
[12:19:43.463] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[12:19:43.464] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:19:43.533] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:19:44.200] INFO: Expecting 41600 events.
[12:19:48.196] INFO: 41600 events read in total (3470ms).
[12:19:48.201] INFO: Test took 4668ms.
[12:19:48.345] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:48.345] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 881604
[12:19:48.345] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[12:19:48.346] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:19:48.412] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:19:49.077] INFO: Expecting 41600 events.
[12:19:53.053] INFO: 41600 events read in total (3449ms).
[12:19:53.059] INFO: Test took 4647ms.
[12:19:53.203] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:53.203] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 881589
[12:19:53.203] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[12:19:53.203] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:19:53.271] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:19:53.935] INFO: Expecting 41600 events.
[12:19:57.728] INFO: 41600 events read in total (3266ms).
[12:19:57.733] INFO: Test took 4462ms.
[12:19:57.875] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:19:57.875] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 874849
[12:19:57.875] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[12:19:57.875] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:19:58.163] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 164 eff = 0.998702
[12:19:58.163] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 176 eff = 0.999279
[12:19:58.163] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 164 eff = 0.997861
[12:19:58.163] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 165 eff = 0.99738
[12:19:58.164] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 164 eff = 0.995817
[12:19:58.164] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 168 eff = 0.996106
[12:19:58.164] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 163 eff = 0.995264
[12:19:58.164] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 177 eff = 0.995841
[12:19:58.165] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 156 eff = 0.996082
[12:19:58.165] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 182 eff = 0.995577
[12:19:58.165] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 183 eff = 0.995697
[12:19:58.165] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 158 eff = 0.99625
[12:19:58.165] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 168 eff = 0.996707
[12:19:58.166] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 173 eff = 0.998197
[12:19:58.166] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 145 eff = 0.999423
[12:19:58.166] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 149 eff = 0.999183
[12:20:04.733] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[12:20:04.733] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[12:20:04.733] INFO: ----------------------------------------------------------------------
[12:20:04.733] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:20:04.733] INFO: ----------------------------------------------------------------------
[12:20:04.734] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[12:20:04.734] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[12:20:04.734] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[12:20:04.734] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[12:20:04.734] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[12:20:04.734] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[12:20:04.734] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[12:20:04.734] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[12:20:04.734] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[12:20:04.734] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[12:20:04.734] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[12:20:04.734] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[12:20:04.752] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:20:05.424] INFO: Expecting 208000 events.
[12:20:22.263] INFO: 208000 events read in total (16312ms).
[12:20:22.284] INFO: Test took 17532ms.
[12:20:23.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:20:23.039] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4096838
[12:20:23.039] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[12:20:23.039] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:20:23.546] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:20:23.546] INFO: number of red-efficiency pixels: 276 178 355 603 703 800 793 789 641 844 839 701 702 429 195 245
[12:20:23.546] INFO: number of X-ray hits detected: 179781 118693 193895 283407 306709 312584 321223 297837 291168 336125 328001 298899 302197 199825 116447 143487
[12:20:23.546] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:20:23.546] INFO: number of Vcal hits detected: 207709 207811 207603 207303 207206 207068 207068 207060 207281 207006 207024 207204 207217 207519 207795 207740
[12:20:23.546] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.6 99.6 99.6 99.7 99.6 99.6 99.7 99.6 99.8 99.9 99.9
[12:20:23.546] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.6 99.6 99.5 99.7 99.5 99.5 99.6 99.6 99.8 99.9 99.9
[12:20:23.546] INFO: X-ray hit rate [MHz/cm2]: 52.7 34.8 56.8 83.1 89.9 91.6 94.2 87.3 85.3 98.5 96.1 87.6 88.6 58.6 34.1 42.1
[12:20:23.546] INFO: PixTestHighRate::doXPixelAlive() done
[12:20:23.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[12:20:23.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[12:20:23.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[12:20:23.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[12:20:23.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[12:20:23.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[12:20:23.591] INFO: PixTest:: pg_setup set to default.
[12:20:45.293] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:20:45.293] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[12:20:57.005] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:20:57.006] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[12:20:57.007] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 469.5mA
[12:20:57.007] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[12:20:57.007] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:20:57.007] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:20:57.007] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:20:57.007] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:20:57.007] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:20:57.007] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:20:57.007] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:20:57.007] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:20:57.007] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:20:57.007] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:20:57.099] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:20:57.099] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:20:57.112] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:20:57.112] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:20:57.113] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:20:57.113] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:20:57.113] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:20:57.113] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:20:57.115] QUIET: Connection to board 58 closed.
[12:20:57.195] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-904-gd94b9f2 on branch master