[08:31:01.057] INFO: *** Welcome to pxar ***
[08:31:01.057] INFO: *** Today: 2016/06/03
[08:31:01.080] INFO: *** Version: v1.9.0-805-geb36
[08:31:01.080] INFO: readRocDacs: data/mk120/dacParameters35_C0.dat .. data/mk120/dacParameters35_C15.dat
[08:31:01.081] INFO: readTbmDacs: data/mk120/tbmParameters_C0a.dat .. data/mk120/tbmParameters_C0b.dat
[08:31:01.081] INFO: readMaskFile: data/mk120/defaultMaskFile.dat
[08:31:01.081] INFO: readTrimFile: data/mk120/trimParameters35_C0.dat .. data/mk120/trimParameters35_C15.dat
[08:31:01.230] INFO: clk: 4
[08:31:01.230] INFO: ctr: 4
[08:31:01.230] INFO: sda: 19
[08:31:01.230] INFO: tin: 9
[08:31:01.230] INFO: level: 15
[08:31:01.230] INFO: triggerdelay: 0
[08:31:01.230] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[08:31:01.230] INFO: Log level: DEBUG
[08:31:01.241] QUIET: Connection to board DTB_WRPRHI opened.
[08:31:01.245] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[08:31:01.248] INFO: RPC call hashes of host and DTB match: 398089610
[08:31:02.847] INFO: DUT info:
[08:31:02.847] INFO: The DUT currently contains the following objects:
[08:31:02.847] INFO: 2 TBM Cores tbm08c (2 ON)
[08:31:02.847] INFO: TBM Core alpha (0): 7 registers set
[08:31:02.847] INFO: TBM Core beta (1): 7 registers set
[08:31:02.847] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:31:02.847] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.847] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.847] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.847] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.847] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.847] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.847] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.847] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.847] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.847] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.847] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.847] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.847] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.847] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.848] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.848] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:31:02.848] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[08:31:02.849] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:31:02.850] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:31:02.852] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35147776
[08:31:02.852] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x299abe0
[08:31:02.852] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x273ce60
[08:31:02.852] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fd54dd94010
[08:31:02.852] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fd553fff510
[08:31:02.852] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35155968 fPxarMemory = 0x7fd54dd94010
[08:31:02.854] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[08:31:02.855] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[08:31:02.855] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[08:31:03.343] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2d85820
[08:31:03.407] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[08:31:03.407] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[08:31:03.407] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[08:31:03.413] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[08:31:03.413] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[08:31:03.413] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[08:31:03.423] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[08:31:03.423] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[08:31:03.438] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[08:31:03.439] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[08:31:03.454] INFO: PixTestCmd::init()
[08:31:03.467] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[08:31:03.467] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[08:31:03.467] INFO: readGainPedestalParameters data/mk120/phCalibrationFitErr35_C0.dat .. data/mk120/phCalibrationFitErr35_C15.dat
[08:31:03.716] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[08:31:03.718] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[08:31:03.732] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[08:31:03.739] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[08:31:03.739] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[08:31:03.739] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[08:31:03.768] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[08:31:03.768] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[08:31:03.768] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[08:31:03.768] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[08:31:03.768] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[08:31:03.775] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[08:31:03.775] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[08:31:03.797] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[08:31:03.798] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[08:31:03.798] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[08:31:03.798] INFO: readReadbackCal: data/mk120/readbackCal_C0.dat .. data/mk120/readbackCal_C15.dat
[08:31:03.807] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[08:31:03.823] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[08:31:03.823] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[08:31:03.823] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[08:31:03.823] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[08:31:03.836] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[08:31:03.837] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[08:31:08.967] INFO: ######################################################################
[08:31:08.967] INFO: PixTestXray::doTest()
[08:31:08.967] INFO: ######################################################################
[08:31:08.967] INFO: ----------------------------------------------------------------------
[08:31:08.967] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:31:08.967] INFO: ----------------------------------------------------------------------
[08:31:09.940] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:31:21.064] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[08:31:21.067] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:31:45.049] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1096027 events.
[08:31:52.687] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1096027, pixels seen in all events: 7099560
[08:31:52.868] INFO: Resuming triggers.
[08:32:03.997] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[08:32:04.117] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:32:27.968] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1096296 events.
[08:32:35.618] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1096296, pixels seen in all events: 7095946
[08:32:35.792] INFO: Resuming triggers.
[08:32:46.919] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[08:32:47.041] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:33:10.987] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1096248 events.
[08:33:18.647] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1096248, pixels seen in all events: 7096705
[08:33:18.819] INFO: Resuming triggers.
[08:33:29.952] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[08:33:29.979] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:33:53.883] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1096788 events.
[08:34:01.643] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1096788, pixels seen in all events: 7089955
[08:34:01.724] INFO: Resuming triggers.
[08:34:12.856] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[08:34:12.886] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:34:36.799] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1096698 events.
[08:34:44.548] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1096698, pixels seen in all events: 7090702
[08:34:44.732] INFO: Resuming triggers.
[08:34:55.867] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[08:34:55.902] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:35:19.691] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1096973 events.
[08:35:27.319] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1096973, pixels seen in all events: 7087651
[08:35:27.586] INFO: Resuming triggers.
[08:35:38.723] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[08:35:38.750] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:36:02.722] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1097257 events.
[08:36:10.435] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1097257, pixels seen in all events: 7084180
[08:36:10.610] INFO: Resuming triggers.
[08:36:21.748] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[08:36:21.775] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:36:45.708] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1097255 events.
[08:36:53.350] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1097255, pixels seen in all events: 7083921
[08:36:53.620] INFO: Resuming triggers.
[08:37:04.701] INFO: data taking finished, elapsed time: 100 seconds.
[08:37:04.897] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:37:28.594] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1091568 events.
[08:37:36.270] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1091568, pixels seen in all events: 7050464
[08:37:36.447] INFO: PixTest:: pg_setup set to default.
[08:37:36.486] INFO: PixTestXray::doPhRun() done
[08:37:36.486] INFO: PixTestXray::doTest() done
[08:37:55.935] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[08:37:55.936] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.9mA
[08:37:55.936] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[08:37:55.936] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[08:37:55.936] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[08:37:55.936] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[08:37:55.936] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[08:37:55.937] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[08:37:55.937] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[08:37:55.937] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[08:37:55.937] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[08:37:55.937] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[08:37:55.937] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[08:37:55.937] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[08:37:55.937] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[08:37:55.937] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[08:37:55.937] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[08:37:55.937] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[08:37:55.937] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[08:37:55.937] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[08:37:55.937] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[08:37:55.937] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[08:37:56.228] QUIET: Connection to board 58 closed.
[08:37:56.307] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[08:38:00.867] INFO: *** Welcome to pxar ***
[08:38:00.867] INFO: *** Today: 2016/06/03
[08:38:00.891] INFO: *** Version: v1.9.0-805-geb36
[08:38:00.891] INFO: readRocDacs: data/mk120/dacParameters35_C0.dat .. data/mk120/dacParameters35_C15.dat
[08:38:00.892] INFO: readTbmDacs: data/mk120/tbmParameters_C0a.dat .. data/mk120/tbmParameters_C0b.dat
[08:38:00.892] INFO: readMaskFile: data/mk120/defaultMaskFile.dat
[08:38:00.892] INFO: readTrimFile: data/mk120/trimParameters35_C0.dat .. data/mk120/trimParameters35_C15.dat
[08:38:01.041] INFO: clk: 4
[08:38:01.041] INFO: ctr: 4
[08:38:01.041] INFO: sda: 19
[08:38:01.041] INFO: tin: 9
[08:38:01.041] INFO: level: 15
[08:38:01.041] INFO: triggerdelay: 0
[08:38:01.041] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[08:38:01.041] INFO: Log level: DEBUG
[08:38:01.053] QUIET: Connection to board DTB_WRPRHI opened.
[08:38:01.056] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[08:38:01.059] INFO: RPC call hashes of host and DTB match: 398089610
[08:38:02.658] INFO: DUT info:
[08:38:02.659] INFO: The DUT currently contains the following objects:
[08:38:02.659] INFO: 2 TBM Cores tbm08c (2 ON)
[08:38:02.659] INFO: TBM Core alpha (0): 7 registers set
[08:38:02.659] INFO: TBM Core beta (1): 7 registers set
[08:38:02.659] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:38:02.659] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:02.659] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[08:38:02.659] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:38:02.659] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:38:02.659] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:38:02.659] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:38:02.659] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[08:38:02.659] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:38:02.659] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[08:38:02.659] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[08:38:02.659] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:38:02.659] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:38:02.659] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[08:38:02.659] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:38:02.659] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[08:38:02.660] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[08:38:02.661] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[08:38:02.662] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:38:02.662] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:38:02.662] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:38:02.662] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[08:38:02.662] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[08:38:02.662] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[08:38:02.662] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:38:02.662] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[08:38:02.662] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[08:38:02.662] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[08:38:02.662] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[08:38:02.662] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:38:02.662] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:38:02.664] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33734656
[08:38:02.664] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x18afbe0
[08:38:02.664] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1651e60
[08:38:02.664] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f3e59d94010
[08:38:02.664] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f3e5ffff510
[08:38:02.664] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33742848 fPxarMemory = 0x7f3e59d94010
[08:38:02.665] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[08:38:02.666] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[08:38:02.666] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[08:38:03.155] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1c9a800
[08:38:03.214] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[08:38:03.214] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[08:38:03.214] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[08:38:03.220] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[08:38:03.220] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[08:38:03.220] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[08:38:03.229] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[08:38:03.229] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[08:38:03.242] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[08:38:03.243] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[08:38:03.258] INFO: PixTestCmd::init()
[08:38:03.271] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[08:38:03.271] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[08:38:03.271] INFO: readGainPedestalParameters data/mk120/phCalibrationFitErr35_C0.dat .. data/mk120/phCalibrationFitErr35_C15.dat
[08:38:03.515] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[08:38:03.516] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[08:38:03.529] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[08:38:03.535] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[08:38:03.535] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[08:38:03.535] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[08:38:03.560] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[08:38:03.560] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[08:38:03.560] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[08:38:03.560] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[08:38:03.560] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[08:38:03.566] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[08:38:03.566] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[08:38:03.590] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[08:38:03.590] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[08:38:03.590] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[08:38:03.590] INFO: readReadbackCal: data/mk120/readbackCal_C0.dat .. data/mk120/readbackCal_C15.dat
[08:38:03.599] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[08:38:03.607] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[08:38:03.607] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[08:38:03.607] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[08:38:03.607] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[08:38:03.628] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[08:38:03.628] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[08:38:18.759] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[08:38:20.111] INFO: ######################################################################
[08:38:20.111] INFO: PixTestXray::doTest()
[08:38:20.111] INFO: ######################################################################
[08:38:20.111] INFO: ----------------------------------------------------------------------
[08:38:20.111] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[08:38:20.111] INFO: ----------------------------------------------------------------------
[08:38:21.083] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[08:38:28.568] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[08:38:28.572] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:38:51.150] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 737472 events.
[08:39:03.318] DEBUG: <PixTestXray.cc/processData:L809> # events read: 737472, pixels seen in all events: 11403251
[08:39:03.551] INFO: Resuming triggers.
[08:39:11.037] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[08:39:11.064] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:39:33.803] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 737410 events.
[08:39:45.947] DEBUG: <PixTestXray.cc/processData:L809> # events read: 737410, pixels seen in all events: 11402452
[08:39:46.132] INFO: Resuming triggers.
[08:39:53.617] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[08:39:53.677] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:40:16.248] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 737385 events.
[08:40:28.397] DEBUG: <PixTestXray.cc/processData:L809> # events read: 737385, pixels seen in all events: 11403734
[08:40:28.611] INFO: Resuming triggers.
[08:40:36.097] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[08:40:36.127] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:40:58.727] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 737472 events.
[08:41:10.004] DEBUG: <PixTestXray.cc/processData:L809> # events read: 737472, pixels seen in all events: 11402086
[08:41:10.217] INFO: Resuming triggers.
[08:41:17.702] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[08:41:17.731] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:41:40.541] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 737413 events.
[08:41:51.833] DEBUG: <PixTestXray.cc/processData:L809> # events read: 737413, pixels seen in all events: 11402635
[08:41:52.040] INFO: Resuming triggers.
[08:41:59.529] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[08:41:59.558] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:42:22.198] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 737741 events.
[08:42:33.489] DEBUG: <PixTestXray.cc/processData:L809> # events read: 737741, pixels seen in all events: 11398905
[08:42:33.694] INFO: Resuming triggers.
[08:42:41.181] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[08:42:41.209] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:43:03.892] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 737531 events.
[08:43:15.183] DEBUG: <PixTestXray.cc/processData:L809> # events read: 737531, pixels seen in all events: 11401262
[08:43:15.391] INFO: Resuming triggers.
[08:43:22.879] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[08:43:22.908] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:43:45.633] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 737731 events.
[08:43:56.821] DEBUG: <PixTestXray.cc/processData:L809> # events read: 737731, pixels seen in all events: 11398693
[08:43:57.027] INFO: Resuming triggers.
[08:43:57.220] INFO: data taking finished, elapsed time: 60 seconds.
[08:43:57.417] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[08:43:58.178] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 18951 events.
[08:43:58.469] DEBUG: <PixTestXray.cc/processData:L809> # events read: 18951, pixels seen in all events: 292895
[08:43:58.500] INFO: PixTest:: pg_setup set to default.
[08:43:58.544] INFO: PixTestXray::doPhRun() done
[08:43:58.544] INFO: PixTestXray::doTest() done
[08:44:55.871] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[08:44:55.872] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.9mA
[08:44:55.872] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[08:44:55.872] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[08:44:55.872] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[08:44:55.873] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[08:44:55.873] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[08:44:55.873] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[08:44:55.873] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[08:44:55.873] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[08:44:55.873] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[08:44:55.873] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[08:44:55.873] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[08:44:55.873] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[08:44:55.873] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[08:44:55.873] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[08:44:55.873] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[08:44:55.873] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[08:44:55.873] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[08:44:55.873] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[08:44:55.874] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[08:44:55.874] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[08:44:56.181] QUIET: Connection to board 58 closed.
[08:44:56.261] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[08:24:54.996] INFO: *** Welcome to pxar ***
[08:24:54.997] INFO: *** Today: 2016/06/03
[08:24:55.019] INFO: *** Version: v1.9.0-805-geb36
[08:24:55.019] INFO: readRocDacs: data/mk120/dacParameters35_C0.dat .. data/mk120/dacParameters35_C15.dat
[08:24:55.020] INFO: readTbmDacs: data/mk120/tbmParameters_C0a.dat .. data/mk120/tbmParameters_C0b.dat
[08:24:55.020] INFO: readMaskFile: data/mk120/defaultMaskFile.dat
[08:24:55.020] INFO: readTrimFile: data/mk120/trimParameters35_C0.dat .. data/mk120/trimParameters35_C15.dat
[08:24:55.169] INFO: clk: 4
[08:24:55.169] INFO: ctr: 4
[08:24:55.169] INFO: sda: 19
[08:24:55.169] INFO: tin: 9
[08:24:55.169] INFO: level: 15
[08:24:55.169] INFO: triggerdelay: 0
[08:24:55.169] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[08:24:55.169] INFO: Log level: DEBUG
[08:24:55.180] QUIET: Connection to board DTB_WRPRHI opened.
[08:24:55.184] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[08:24:55.186] INFO: RPC call hashes of host and DTB match: 398089610
[08:24:56.786] INFO: DUT info:
[08:24:56.786] INFO: The DUT currently contains the following objects:
[08:24:56.786] INFO: 2 TBM Cores tbm08c (2 ON)
[08:24:56.786] INFO: TBM Core alpha (0): 7 registers set
[08:24:56.786] INFO: TBM Core beta (1): 7 registers set
[08:24:56.786] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:24:56.786] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.786] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.786] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.786] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.786] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.786] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.786] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.786] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.786] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.786] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.787] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.787] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.787] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.787] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.787] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.787] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[08:24:56.787] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[08:24:56.788] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:24:56.789] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:24:56.791] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33087488
[08:24:56.791] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1c82be0
[08:24:56.791] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1a24e60
[08:24:56.791] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f7471d94010
[08:24:56.791] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f7477fff510
[08:24:56.792] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33095680 fPxarMemory = 0x7f7471d94010
[08:24:56.793] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 382.7mA
[08:24:56.794] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[08:24:56.794] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[08:24:57.283] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x206d7a0
[08:24:57.343] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[08:24:57.343] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[08:24:57.343] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[08:24:57.349] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[08:24:57.349] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[08:24:57.349] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[08:24:57.363] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[08:24:57.363] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[08:24:57.380] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[08:24:57.380] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[08:24:57.399] INFO: PixTestCmd::init()
[08:24:57.403] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[08:24:57.403] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[08:24:57.403] INFO: readGainPedestalParameters data/mk120/phCalibrationFitErr35_C0.dat .. data/mk120/phCalibrationFitErr35_C15.dat
[08:24:57.645] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[08:24:57.645] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[08:24:57.659] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[08:24:57.665] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[08:24:57.665] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[08:24:57.665] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[08:24:57.691] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[08:24:57.691] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[08:24:57.691] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[08:24:57.691] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[08:24:57.691] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[08:24:57.697] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[08:24:57.697] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[08:24:57.716] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[08:24:57.716] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[08:24:57.716] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[08:24:57.716] INFO: readReadbackCal: data/mk120/readbackCal_C0.dat .. data/mk120/readbackCal_C15.dat
[08:24:57.726] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[08:24:57.735] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[08:24:57.735] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[08:24:57.735] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[08:24:57.735] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[08:24:57.753] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[08:24:57.753] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[08:25:03.042] INFO: ######################################################################
[08:25:03.042] INFO: PixTestAlive::doTest()
[08:25:03.042] INFO: ######################################################################
[08:25:03.045] INFO: ----------------------------------------------------------------------
[08:25:03.045] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:25:03.045] INFO: ----------------------------------------------------------------------
[08:25:03.048] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:25:03.423] INFO: Expecting 41600 events.
[08:25:07.699] INFO: 41600 events read in total (3558ms).
[08:25:07.847] INFO: Test took 4799ms.
[08:25:07.856] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:07.856] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[08:25:07.856] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[08:25:08.134] INFO: PixTestAlive::aliveTest() done
[08:25:08.134] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 2 0 0 0 0 0 0 0
[08:25:08.135] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 22 18 21 43 52 45 40 33 35 45 41 39 39 36 15 25
[08:25:08.157] INFO: ----------------------------------------------------------------------
[08:25:08.157] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:25:08.157] INFO: ----------------------------------------------------------------------
[08:25:08.159] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:25:08.532] INFO: Expecting 41600 events.
[08:25:11.580] INFO: 41600 events read in total (2332ms).
[08:25:11.580] INFO: Test took 3421ms.
[08:25:11.580] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:11.580] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[08:25:11.580] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[08:25:11.581] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:25:12.003] INFO: PixTestAlive::maskTest() done
[08:25:12.003] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:25:12.021] INFO: ----------------------------------------------------------------------
[08:25:12.021] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:25:12.021] INFO: ----------------------------------------------------------------------
[08:25:12.024] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:25:12.399] INFO: Expecting 41600 events.
[08:25:16.666] INFO: 41600 events read in total (3552ms).
[08:25:16.668] INFO: Test took 4644ms.
[08:25:16.679] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:16.679] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[08:25:16.679] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[08:25:17.050] INFO: PixTestAlive::addressDecodingTest() done
[08:25:17.050] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:25:17.050] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[08:25:19.006] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[08:25:19.007] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[08:25:19.007] INFO: ----------------------------------------------------------------------
[08:25:19.007] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[08:25:19.007] INFO: ----------------------------------------------------------------------
[08:25:19.145] INFO: Expecting 768 events.
[08:25:20.277] INFO: 768 events read in total (417ms).
[08:25:20.278] INFO: Test took 1266ms.
[08:25:20.284] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:25:21.082] INFO: Expecting 41600 events.
[08:25:24.312] INFO: 41600 events read in total (2704ms).
[08:25:24.317] INFO: Test took 4033ms.
[08:25:24.407] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:24.407] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 510716
[08:25:24.407] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[08:25:24.407] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:25:24.448] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:25:25.113] INFO: Expecting 41600 events.
[08:25:28.482] INFO: 41600 events read in total (2842ms).
[08:25:28.486] INFO: Test took 4038ms.
[08:25:28.574] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:28.575] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 521050
[08:25:28.575] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[08:25:28.575] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:25:28.617] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:25:29.282] INFO: Expecting 41600 events.
[08:25:32.677] INFO: 41600 events read in total (2868ms).
[08:25:32.681] INFO: Test took 4064ms.
[08:25:32.769] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:32.769] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 521658
[08:25:32.769] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[08:25:32.769] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:25:32.813] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:25:33.480] INFO: Expecting 41600 events.
[08:25:36.879] INFO: 41600 events read in total (2872ms).
[08:25:36.883] INFO: Test took 4070ms.
[08:25:36.970] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:36.970] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 522204
[08:25:36.970] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[08:25:36.971] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:25:37.015] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:25:37.682] INFO: Expecting 41600 events.
[08:25:41.082] INFO: 41600 events read in total (2874ms).
[08:25:41.086] INFO: Test took 4071ms.
[08:25:41.175] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:41.175] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 523505
[08:25:41.175] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[08:25:41.176] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:25:41.220] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:25:41.887] INFO: Expecting 41600 events.
[08:25:45.301] INFO: 41600 events read in total (2887ms).
[08:25:45.305] INFO: Test took 4085ms.
[08:25:45.401] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:45.401] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 525467
[08:25:45.401] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[08:25:45.402] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:25:45.445] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:25:46.112] INFO: Expecting 41600 events.
[08:25:49.524] INFO: 41600 events read in total (2885ms).
[08:25:49.529] INFO: Test took 4084ms.
[08:25:49.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:49.618] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 523125
[08:25:49.618] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[08:25:49.618] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:25:49.660] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:25:50.325] INFO: Expecting 41600 events.
[08:25:53.738] INFO: 41600 events read in total (2887ms).
[08:25:53.742] INFO: Test took 4082ms.
[08:25:53.828] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:53.828] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 522969
[08:25:53.828] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[08:25:53.828] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:25:53.869] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:25:54.534] INFO: Expecting 41600 events.
[08:25:57.952] INFO: 41600 events read in total (2891ms).
[08:25:57.956] INFO: Test took 4087ms.
[08:25:58.045] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:58.045] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 523515
[08:25:58.045] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[08:25:58.046] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:25:58.090] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:25:58.757] INFO: Expecting 41600 events.
[08:26:02.182] INFO: 41600 events read in total (2899ms).
[08:26:02.186] INFO: Test took 4096ms.
[08:26:02.279] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:02.279] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 524862
[08:26:02.279] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[08:26:02.279] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:26:02.322] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:26:02.987] INFO: Expecting 41600 events.
[08:26:06.406] INFO: 41600 events read in total (2892ms).
[08:26:06.409] INFO: Test took 4087ms.
[08:26:06.495] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:06.495] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 523461
[08:26:06.495] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[08:26:06.496] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:26:06.537] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:26:07.202] INFO: Expecting 41600 events.
[08:26:10.622] INFO: 41600 events read in total (2893ms).
[08:26:10.626] INFO: Test took 4089ms.
[08:26:10.711] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:10.711] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 523016
[08:26:10.711] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[08:26:10.712] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:26:10.754] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:26:11.419] INFO: Expecting 41600 events.
[08:26:14.850] INFO: 41600 events read in total (2905ms).
[08:26:14.854] INFO: Test took 4100ms.
[08:26:14.940] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:14.941] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 522569
[08:26:14.941] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[08:26:14.941] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:26:14.983] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:26:15.648] INFO: Expecting 41600 events.
[08:26:19.075] INFO: 41600 events read in total (2901ms).
[08:26:19.079] INFO: Test took 4096ms.
[08:26:19.166] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:19.166] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 523830
[08:26:19.166] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[08:26:19.166] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:26:19.208] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:26:19.873] INFO: Expecting 41600 events.
[08:26:23.304] INFO: 41600 events read in total (2905ms).
[08:26:23.308] INFO: Test took 4100ms.
[08:26:23.399] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:23.399] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 524488
[08:26:23.399] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[08:26:23.399] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:26:23.443] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:26:24.108] INFO: Expecting 41600 events.
[08:26:27.544] INFO: 41600 events read in total (2910ms).
[08:26:27.548] INFO: Test took 4105ms.
[08:26:27.634] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:27.634] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 523885
[08:26:27.634] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[08:26:27.635] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:26:27.677] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:26:28.343] INFO: Expecting 41600 events.
[08:26:31.778] INFO: 41600 events read in total (2909ms).
[08:26:31.782] INFO: Test took 4105ms.
[08:26:31.869] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:31.869] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 521976
[08:26:31.869] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[08:26:31.869] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:26:31.911] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:26:32.576] INFO: Expecting 41600 events.
[08:26:35.998] INFO: 41600 events read in total (2895ms).
[08:26:36.002] INFO: Test took 4091ms.
[08:26:36.088] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:36.088] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 522376
[08:26:36.088] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[08:26:36.089] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:26:36.130] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:26:36.796] INFO: Expecting 41600 events.
[08:26:40.221] INFO: 41600 events read in total (2898ms).
[08:26:40.225] INFO: Test took 4095ms.
[08:26:40.311] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:40.311] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 522132
[08:26:40.311] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[08:26:40.312] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:26:40.354] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:26:41.019] INFO: Expecting 41600 events.
[08:26:44.351] INFO: 41600 events read in total (2805ms).
[08:26:44.355] INFO: Test took 4001ms.
[08:26:44.440] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:44.440] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 518882
[08:26:44.440] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[08:26:44.440] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:26:44.705] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 148 eff = 0.999495
[08:26:44.705] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 134 eff = 0.999688
[08:26:44.705] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 184 eff = 0.999207
[08:26:44.705] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 162 eff = 0.998846
[08:26:44.705] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 161 eff = 0.998413
[08:26:44.705] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 152 eff = 0.99875
[08:26:44.705] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 164 eff = 0.998582
[08:26:44.706] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 164 eff = 0.999014
[08:26:44.706] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 163 eff = 0.998365
[08:26:44.706] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 159 eff = 0.998726
[08:26:44.706] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 174 eff = 0.99875
[08:26:44.706] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 167 eff = 0.99899
[08:26:44.706] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 152 eff = 0.998966
[08:26:44.706] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 193 eff = 0.999447
[08:26:44.707] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 160 eff = 0.999663
[08:26:44.707] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 151 eff = 0.999639
[08:27:51.751] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[08:27:51.751] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[08:27:51.751] INFO: ----------------------------------------------------------------------
[08:27:51.751] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[08:27:51.751] INFO: ----------------------------------------------------------------------
[08:27:51.751] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[08:27:51.751] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[08:27:51.751] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[08:27:51.751] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[08:27:51.751] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[08:27:51.751] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[08:27:51.751] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[08:27:51.751] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[08:27:51.751] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[08:27:51.751] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[08:27:51.751] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[08:27:51.751] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[08:27:51.767] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:27:52.431] INFO: Expecting 208000 events.
[08:28:05.423] INFO: 208000 events read in total (12465ms).
[08:28:05.437] INFO: Test took 13670ms.
[08:28:05.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:05.850] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2329990
[08:28:05.850] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[08:28:05.850] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:28:06.234] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 2 0 0 0 0 0 0 0
[08:28:06.234] INFO: number of red-efficiency pixels: 139 97 170 282 257 283 267 222 216 259 283 253 287 163 100 125
[08:28:06.234] INFO: number of X-ray hits detected: 98960 67041 110536 167883 176140 177092 175124 155368 161046 184740 179237 162779 171948 115877 70285 89377
[08:28:06.234] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[08:28:06.234] INFO: number of Vcal hits detected: 207857 207901 207824 207706 207684 207700 207721 207768 207681 207727 207706 207732 207701 207832 207890 207873
[08:28:06.234] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9
[08:28:06.234] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9
[08:28:06.234] INFO: X-ray hit rate [MHz/cm2]: 29.0 19.7 32.4 49.2 51.6 51.9 51.3 45.5 47.2 54.1 52.5 47.7 50.4 34.0 20.6 26.2
[08:28:06.234] INFO: PixTestHighRate::doXPixelAlive() done
[08:28:06.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[08:28:06.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[08:28:06.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[08:28:06.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[08:28:06.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[08:28:06.281] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[08:28:06.281] INFO: PixTest:: pg_setup set to default.
[08:28:20.047] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[08:28:20.047] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.9mA
[08:28:20.048] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[08:28:20.048] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[08:28:20.048] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[08:28:20.048] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[08:28:20.048] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[08:28:20.048] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[08:28:20.048] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[08:28:20.048] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[08:28:20.048] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[08:28:20.048] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[08:28:20.049] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[08:28:20.049] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[08:28:20.125] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[08:28:20.125] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[08:28:20.134] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[08:28:20.134] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[08:28:20.135] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[08:28:20.135] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[08:28:20.135] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[08:28:20.135] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[08:28:20.138] QUIET: Connection to board 58 closed.
[08:28:20.217] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[08:20:48.717] INFO: *** Welcome to pxar ***
[08:20:48.717] INFO: *** Today: 2016/06/03
[08:20:48.740] INFO: *** Version: v1.9.0-805-geb36
[08:20:48.740] INFO: readRocDacs: data/mk120/dacParameters35_C0.dat .. data/mk120/dacParameters35_C15.dat
[08:20:48.741] INFO: readTbmDacs: data/mk120/tbmParameters_C0a.dat .. data/mk120/tbmParameters_C0b.dat
[08:20:48.741] INFO: readMaskFile: data/mk120/defaultMaskFile.dat
[08:20:48.741] INFO: readTrimFile: data/mk120/trimParameters35_C0.dat .. data/mk120/trimParameters35_C15.dat
[08:20:48.890] INFO: clk: 4
[08:20:48.890] INFO: ctr: 4
[08:20:48.890] INFO: sda: 19
[08:20:48.890] INFO: tin: 9
[08:20:48.890] INFO: level: 15
[08:20:48.890] INFO: triggerdelay: 0
[08:20:48.890] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[08:20:48.890] INFO: Log level: DEBUG
[08:20:48.902] QUIET: Connection to board DTB_WRPRHI opened.
[08:20:48.905] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[08:20:48.908] INFO: RPC call hashes of host and DTB match: 398089610
[08:20:50.507] INFO: DUT info:
[08:20:50.507] INFO: The DUT currently contains the following objects:
[08:20:50.507] INFO: 2 TBM Cores tbm08c (2 ON)
[08:20:50.507] INFO: TBM Core alpha (0): 7 registers set
[08:20:50.507] INFO: TBM Core beta (1): 7 registers set
[08:20:50.507] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:20:50.507] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.507] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.507] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.507] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.508] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.508] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.508] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.508] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.508] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.508] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.508] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.508] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.508] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.508] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.508] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.508] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:20:50.508] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:20:50.509] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:20:50.510] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:20:50.513] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33267712
[08:20:50.513] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x27c7be0
[08:20:50.513] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2569e60
[08:20:50.513] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f4f71d94010
[08:20:50.513] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f4f77fff510
[08:20:50.513] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33275904 fPxarMemory = 0x7f4f71d94010
[08:20:50.514] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[08:20:50.515] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[08:20:50.515] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[08:20:51.005] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2bb2800
[08:20:51.067] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[08:20:51.067] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[08:20:51.068] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[08:20:51.073] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[08:20:51.073] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[08:20:51.073] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[08:20:51.083] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[08:20:51.083] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[08:20:51.102] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[08:20:51.102] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[08:20:51.115] INFO: PixTestCmd::init()
[08:20:51.124] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[08:20:51.125] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[08:20:51.125] INFO: readGainPedestalParameters data/mk120/phCalibrationFitErr35_C0.dat .. data/mk120/phCalibrationFitErr35_C15.dat
[08:20:51.367] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[08:20:51.367] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[08:20:51.380] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[08:20:51.386] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[08:20:51.386] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[08:20:51.386] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[08:20:51.411] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[08:20:51.411] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[08:20:51.411] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[08:20:51.411] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[08:20:51.411] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[08:20:51.418] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[08:20:51.418] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[08:20:51.431] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[08:20:51.432] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[08:20:51.432] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[08:20:51.432] INFO: readReadbackCal: data/mk120/readbackCal_C0.dat .. data/mk120/readbackCal_C15.dat
[08:20:51.447] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[08:20:51.455] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[08:20:51.455] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[08:20:51.455] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[08:20:51.455] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[08:20:51.475] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[08:20:51.475] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[08:20:55.458] INFO: ######################################################################
[08:20:55.458] INFO: PixTestAlive::doTest()
[08:20:55.458] INFO: ######################################################################
[08:20:55.461] INFO: ----------------------------------------------------------------------
[08:20:55.461] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:20:55.461] INFO: ----------------------------------------------------------------------
[08:20:55.463] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:20:55.839] INFO: Expecting 41600 events.
[08:21:00.094] INFO: 41600 events read in total (3537ms).
[08:21:00.241] INFO: Test took 4778ms.
[08:21:00.250] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:00.250] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[08:21:00.250] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[08:21:00.525] INFO: PixTestAlive::aliveTest() done
[08:21:00.525] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 2 0 0 0 0 0 0 0
[08:21:00.525] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 43 28 47 52 64 61 75 49 61 62 61 59 56 41 25 32
[08:21:00.547] INFO: ----------------------------------------------------------------------
[08:21:00.547] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:21:00.547] INFO: ----------------------------------------------------------------------
[08:21:00.549] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:21:00.923] INFO: Expecting 41600 events.
[08:21:03.977] INFO: 41600 events read in total (2339ms).
[08:21:03.977] INFO: Test took 3428ms.
[08:21:03.977] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:03.977] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[08:21:03.977] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[08:21:03.978] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:21:04.400] INFO: PixTestAlive::maskTest() done
[08:21:04.400] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:21:04.419] INFO: ----------------------------------------------------------------------
[08:21:04.419] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:21:04.419] INFO: ----------------------------------------------------------------------
[08:21:04.421] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:21:04.795] INFO: Expecting 41600 events.
[08:21:09.044] INFO: 41600 events read in total (3533ms).
[08:21:09.045] INFO: Test took 4624ms.
[08:21:09.056] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:09.057] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[08:21:09.057] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[08:21:09.429] INFO: PixTestAlive::addressDecodingTest() done
[08:21:09.429] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:21:09.429] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[08:21:41.742] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[08:21:41.742] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[08:21:41.742] INFO: ----------------------------------------------------------------------
[08:21:41.743] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[08:21:41.743] INFO: ----------------------------------------------------------------------
[08:21:41.881] INFO: Expecting 768 events.
[08:21:43.013] INFO: 768 events read in total (417ms).
[08:21:43.014] INFO: Test took 1266ms.
[08:21:43.019] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:21:43.817] INFO: Expecting 41600 events.
[08:21:47.326] INFO: 41600 events read in total (2983ms).
[08:21:47.330] INFO: Test took 4311ms.
[08:21:47.461] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:47.461] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 747046
[08:21:47.461] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[08:21:47.461] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:21:47.517] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:21:48.184] INFO: Expecting 41600 events.
[08:21:51.814] INFO: 41600 events read in total (3103ms).
[08:21:51.819] INFO: Test took 4302ms.
[08:21:51.946] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:51.946] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 756339
[08:21:51.946] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[08:21:51.947] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:21:52.006] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:21:52.673] INFO: Expecting 41600 events.
[08:21:56.314] INFO: 41600 events read in total (3114ms).
[08:21:56.319] INFO: Test took 4313ms.
[08:21:56.447] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:56.448] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 757461
[08:21:56.448] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[08:21:56.448] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:21:56.508] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:21:57.175] INFO: Expecting 41600 events.
[08:22:00.826] INFO: 41600 events read in total (3124ms).
[08:22:00.831] INFO: Test took 4323ms.
[08:22:00.958] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:00.958] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 758322
[08:22:00.958] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[08:22:00.958] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:22:01.016] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:22:01.681] INFO: Expecting 41600 events.
[08:22:05.326] INFO: 41600 events read in total (3118ms).
[08:22:05.331] INFO: Test took 4314ms.
[08:22:05.459] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:05.459] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 758634
[08:22:05.459] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[08:22:05.459] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:22:05.519] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:22:06.187] INFO: Expecting 41600 events.
[08:22:09.852] INFO: 41600 events read in total (3139ms).
[08:22:09.857] INFO: Test took 4338ms.
[08:22:09.985] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:09.985] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 757725
[08:22:09.985] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[08:22:09.985] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:22:10.045] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:22:10.712] INFO: Expecting 41600 events.
[08:22:14.390] INFO: 41600 events read in total (3151ms).
[08:22:14.395] INFO: Test took 4350ms.
[08:22:14.521] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:14.521] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 758919
[08:22:14.521] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[08:22:14.521] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:22:14.579] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:22:15.244] INFO: Expecting 41600 events.
[08:22:18.917] INFO: 41600 events read in total (3146ms).
[08:22:18.921] INFO: Test took 4342ms.
[08:22:19.047] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:19.048] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 758987
[08:22:19.048] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[08:22:19.048] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:22:19.105] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:22:19.770] INFO: Expecting 41600 events.
[08:22:23.444] INFO: 41600 events read in total (3147ms).
[08:22:23.449] INFO: Test took 4344ms.
[08:22:23.577] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:23.577] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 757622
[08:22:23.577] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[08:22:23.578] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:22:23.635] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:22:24.300] INFO: Expecting 41600 events.
[08:22:27.976] INFO: 41600 events read in total (3150ms).
[08:22:27.981] INFO: Test took 4346ms.
[08:22:28.109] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:28.109] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 758784
[08:22:28.109] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[08:22:28.109] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:22:28.169] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:22:28.836] INFO: Expecting 41600 events.
[08:22:32.519] INFO: 41600 events read in total (3157ms).
[08:22:32.523] INFO: Test took 4354ms.
[08:22:32.651] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:32.651] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 757420
[08:22:32.651] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[08:22:32.652] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:22:32.709] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:22:33.374] INFO: Expecting 41600 events.
[08:22:37.119] INFO: 41600 events read in total (3175ms).
[08:22:37.123] INFO: Test took 4414ms.
[08:22:37.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:37.249] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 758473
[08:22:37.249] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[08:22:37.249] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:22:37.307] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:22:37.972] INFO: Expecting 41600 events.
[08:22:41.672] INFO: 41600 events read in total (3174ms).
[08:22:41.677] INFO: Test took 4370ms.
[08:22:41.804] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:41.804] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 759354
[08:22:41.804] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[08:22:41.805] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:22:41.862] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:22:42.527] INFO: Expecting 41600 events.
[08:22:46.211] INFO: 41600 events read in total (3157ms).
[08:22:46.216] INFO: Test took 4354ms.
[08:22:46.342] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:46.342] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 757541
[08:22:46.342] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[08:22:46.343] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:22:46.401] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:22:47.066] INFO: Expecting 41600 events.
[08:22:50.750] INFO: 41600 events read in total (3157ms).
[08:22:50.754] INFO: Test took 4353ms.
[08:22:50.880] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:50.880] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 757352
[08:22:50.880] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[08:22:50.880] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:22:50.939] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:22:51.604] INFO: Expecting 41600 events.
[08:22:55.291] INFO: 41600 events read in total (3161ms).
[08:22:55.295] INFO: Test took 4356ms.
[08:22:55.420] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:55.420] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 757687
[08:22:55.420] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[08:22:55.420] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:22:55.478] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:22:56.143] INFO: Expecting 41600 events.
[08:22:59.823] INFO: 41600 events read in total (3153ms).
[08:22:59.828] INFO: Test took 4350ms.
[08:22:59.954] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:59.954] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 757672
[08:22:59.954] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[08:22:59.955] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:23:00.012] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:23:00.678] INFO: Expecting 41600 events.
[08:23:04.365] INFO: 41600 events read in total (3161ms).
[08:23:04.370] INFO: Test took 4358ms.
[08:23:04.495] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:04.495] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 756508
[08:23:04.495] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[08:23:04.496] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:23:04.553] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:23:05.218] INFO: Expecting 41600 events.
[08:23:08.895] INFO: 41600 events read in total (3150ms).
[08:23:08.900] INFO: Test took 4347ms.
[08:23:09.029] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:09.029] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 756511
[08:23:09.029] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[08:23:09.029] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:23:09.086] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:23:09.751] INFO: Expecting 41600 events.
[08:23:13.315] INFO: 41600 events read in total (3037ms).
[08:23:13.320] INFO: Test took 4234ms.
[08:23:13.443] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:13.443] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 753480
[08:23:13.443] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[08:23:13.443] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:23:13.722] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 154 eff = 0.999038
[08:23:13.722] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 141 eff = 0.999423
[08:23:13.722] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 179 eff = 0.998678
[08:23:13.722] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 168 eff = 0.997668
[08:23:13.722] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 161 eff = 0.997452
[08:23:13.723] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 158 eff = 0.997404
[08:23:13.723] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 165 eff = 0.997476
[08:23:13.723] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 161 eff = 0.99762
[08:23:13.723] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 160 eff = 0.997236
[08:23:13.723] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 158 eff = 0.997139
[08:23:13.723] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 175 eff = 0.997284
[08:23:13.723] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 164 eff = 0.997452
[08:23:13.724] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 156 eff = 0.997332
[08:23:13.724] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 195 eff = 0.998389
[08:23:13.724] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 146 eff = 0.999375
[08:23:13.724] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 146 eff = 0.999207
[08:23:56.582] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[08:23:56.583] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[08:23:56.583] INFO: ----------------------------------------------------------------------
[08:23:56.583] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[08:23:56.583] INFO: ----------------------------------------------------------------------
[08:23:56.583] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[08:23:56.583] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[08:23:56.583] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[08:23:56.583] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[08:23:56.583] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[08:23:56.583] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[08:23:56.583] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[08:23:56.583] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[08:23:56.583] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[08:23:56.583] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[08:23:56.583] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[08:23:56.583] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[08:23:56.598] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:23:57.264] INFO: Expecting 208000 events.
[08:24:11.981] INFO: 208000 events read in total (14191ms).
[08:24:11.999] INFO: Test took 15401ms.
[08:24:12.600] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:12.600] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3485555
[08:24:12.600] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[08:24:12.601] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:24:13.065] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 2 0 0 0 0 0 0 0
[08:24:13.065] INFO: number of red-efficiency pixels: 191 162 330 448 515 524 529 448 543 553 529 520 564 340 170 244
[08:24:13.065] INFO: number of X-ray hits detected: 148964 102117 165675 254349 265352 267582 264553 234406 243876 279643 270029 247183 259502 174318 105930 135519
[08:24:13.065] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[08:24:13.065] INFO: number of Vcal hits detected: 207804 207832 207644 207511 207394 207423 207422 207522 207307 207388 207422 207427 207378 207627 207825 207749
[08:24:13.065] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.7 99.7 99.8 99.7 99.7 99.7 99.8 99.7 99.8 99.9 99.9
[08:24:13.065] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.7 99.8 99.7 99.7 99.7 99.7 99.7 99.8 99.9 99.9
[08:24:13.065] INFO: X-ray hit rate [MHz/cm2]: 43.7 29.9 48.6 74.6 77.8 78.4 77.5 68.7 71.5 82.0 79.1 72.5 76.1 51.1 31.0 39.7
[08:24:13.065] INFO: PixTestHighRate::doXPixelAlive() done
[08:24:13.112] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[08:24:13.112] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[08:24:13.112] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[08:24:13.112] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[08:24:13.112] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[08:24:13.112] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[08:24:13.112] INFO: PixTest:: pg_setup set to default.
[08:24:48.319] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[08:24:48.319] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.9mA
[08:24:48.320] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[08:24:48.320] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.5 C
[08:24:48.320] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[08:24:48.320] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[08:24:48.320] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[08:24:48.320] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[08:24:48.320] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[08:24:48.320] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[08:24:48.321] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[08:24:48.321] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[08:24:48.321] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[08:24:48.321] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[08:24:48.399] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[08:24:48.399] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[08:24:48.409] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[08:24:48.409] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[08:24:48.409] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[08:24:48.409] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[08:24:48.409] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[08:24:48.409] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[08:24:48.411] QUIET: Connection to board 58 closed.
[08:24:48.491] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[08:17:54.158] INFO: *** Welcome to pxar ***
[08:17:54.158] INFO: *** Today: 2016/06/03
[08:17:54.181] INFO: *** Version: v1.9.0-805-geb36
[08:17:54.181] INFO: readRocDacs: data/mk120/dacParameters35_C0.dat .. data/mk120/dacParameters35_C15.dat
[08:17:54.182] INFO: readTbmDacs: data/mk120/tbmParameters_C0a.dat .. data/mk120/tbmParameters_C0b.dat
[08:17:54.182] INFO: readMaskFile: data/mk120/defaultMaskFile.dat
[08:17:54.182] INFO: readTrimFile: data/mk120/trimParameters35_C0.dat .. data/mk120/trimParameters35_C15.dat
[08:17:54.331] INFO: clk: 4
[08:17:54.331] INFO: ctr: 4
[08:17:54.331] INFO: sda: 19
[08:17:54.331] INFO: tin: 9
[08:17:54.331] INFO: level: 15
[08:17:54.331] INFO: triggerdelay: 0
[08:17:54.331] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[08:17:54.331] INFO: Log level: DEBUG
[08:17:54.341] QUIET: Connection to board DTB_WRPRHI opened.
[08:17:54.345] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[08:17:54.348] INFO: RPC call hashes of host and DTB match: 398089610
[08:17:55.947] INFO: DUT info:
[08:17:55.947] INFO: The DUT currently contains the following objects:
[08:17:55.947] INFO: 2 TBM Cores tbm08c (2 ON)
[08:17:55.947] INFO: TBM Core alpha (0): 7 registers set
[08:17:55.947] INFO: TBM Core beta (1): 7 registers set
[08:17:55.947] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:17:55.947] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.947] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.947] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.947] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.947] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.947] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.947] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.947] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.947] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.947] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.947] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.947] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.947] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.947] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.947] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.947] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[08:17:55.948] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[08:17:55.949] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[08:17:55.950] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[08:17:55.952] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32755712
[08:17:55.952] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x29cebe0
[08:17:55.952] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2770e60
[08:17:55.952] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f3719d94010
[08:17:55.952] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f371ffff510
[08:17:55.952] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32763904 fPxarMemory = 0x7f3719d94010
[08:17:55.954] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 382.7mA
[08:17:55.955] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[08:17:55.955] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[08:17:56.443] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2db98c0
[08:17:56.506] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[08:17:56.506] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[08:17:56.506] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[08:17:56.511] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[08:17:56.511] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[08:17:56.512] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[08:17:56.521] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[08:17:56.521] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[08:17:56.535] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[08:17:56.536] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[08:17:56.554] INFO: PixTestCmd::init()
[08:17:56.558] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[08:17:56.558] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[08:17:56.558] INFO: readGainPedestalParameters data/mk120/phCalibrationFitErr35_C0.dat .. data/mk120/phCalibrationFitErr35_C15.dat
[08:17:56.800] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[08:17:56.801] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[08:17:56.814] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[08:17:56.821] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[08:17:56.821] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[08:17:56.821] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[08:17:56.848] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[08:17:56.848] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[08:17:56.848] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[08:17:56.848] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[08:17:56.848] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[08:17:56.854] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[08:17:56.854] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[08:17:56.869] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[08:17:56.869] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[08:17:56.869] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[08:17:56.870] INFO: readReadbackCal: data/mk120/readbackCal_C0.dat .. data/mk120/readbackCal_C15.dat
[08:17:56.881] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[08:17:56.889] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[08:17:56.889] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[08:17:56.889] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[08:17:56.889] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[08:17:56.910] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[08:17:56.911] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[08:18:00.986] INFO: ######################################################################
[08:18:00.986] INFO: PixTestAlive::doTest()
[08:18:00.986] INFO: ######################################################################
[08:18:00.989] INFO: ----------------------------------------------------------------------
[08:18:00.989] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:18:00.989] INFO: ----------------------------------------------------------------------
[08:18:00.992] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:18:01.367] INFO: Expecting 41600 events.
[08:18:05.611] INFO: 41600 events read in total (3526ms).
[08:18:05.759] INFO: Test took 4767ms.
[08:18:05.768] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:05.768] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[08:18:05.769] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[08:18:06.044] INFO: PixTestAlive::aliveTest() done
[08:18:06.044] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 2 0 0 0 0 0 0 0
[08:18:06.044] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 42 27 49 79 83 86 87 79 78 88 62 76 89 49 31 47
[08:18:06.067] INFO: ----------------------------------------------------------------------
[08:18:06.067] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:18:06.067] INFO: ----------------------------------------------------------------------
[08:18:06.069] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:18:06.444] INFO: Expecting 41600 events.
[08:18:09.490] INFO: 41600 events read in total (2331ms).
[08:18:09.490] INFO: Test took 3421ms.
[08:18:09.490] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:09.490] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[08:18:09.490] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[08:18:09.491] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:18:09.913] INFO: PixTestAlive::maskTest() done
[08:18:09.913] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:18:09.934] INFO: ----------------------------------------------------------------------
[08:18:09.934] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:18:09.934] INFO: ----------------------------------------------------------------------
[08:18:09.936] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:18:10.310] INFO: Expecting 41600 events.
[08:18:14.561] INFO: 41600 events read in total (3536ms).
[08:18:14.562] INFO: Test took 4626ms.
[08:18:14.573] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:14.573] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[08:18:14.573] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[08:18:14.947] INFO: PixTestAlive::addressDecodingTest() done
[08:18:14.947] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:18:14.947] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[08:18:21.158] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[08:18:21.158] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[08:18:21.159] INFO: ----------------------------------------------------------------------
[08:18:21.159] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[08:18:21.159] INFO: ----------------------------------------------------------------------
[08:18:21.298] INFO: Expecting 768 events.
[08:18:22.430] INFO: 768 events read in total (417ms).
[08:18:22.430] INFO: Test took 1266ms.
[08:18:22.436] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:18:23.234] INFO: Expecting 41600 events.
[08:18:26.963] INFO: 41600 events read in total (3203ms).
[08:18:26.968] INFO: Test took 4532ms.
[08:18:27.127] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:27.127] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 924281
[08:18:27.127] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[08:18:27.127] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:18:27.194] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:18:27.859] INFO: Expecting 41600 events.
[08:18:31.689] INFO: 41600 events read in total (3304ms).
[08:18:31.694] INFO: Test took 4500ms.
[08:18:31.844] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:31.844] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 933072
[08:18:31.844] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[08:18:31.845] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:18:31.914] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:18:32.579] INFO: Expecting 41600 events.
[08:18:36.442] INFO: 41600 events read in total (3336ms).
[08:18:36.447] INFO: Test took 4533ms.
[08:18:36.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:36.605] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 934345
[08:18:36.605] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[08:18:36.606] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:18:36.673] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:18:37.338] INFO: Expecting 41600 events.
[08:18:41.201] INFO: 41600 events read in total (3336ms).
[08:18:41.206] INFO: Test took 4533ms.
[08:18:41.356] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:41.356] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 933113
[08:18:41.356] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[08:18:41.356] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:18:41.426] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:18:42.091] INFO: Expecting 41600 events.
[08:18:45.957] INFO: 41600 events read in total (3340ms).
[08:18:45.963] INFO: Test took 4537ms.
[08:18:46.115] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:46.115] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 936312
[08:18:46.115] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[08:18:46.116] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:18:46.184] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:18:46.849] INFO: Expecting 41600 events.
[08:18:50.729] INFO: 41600 events read in total (3354ms).
[08:18:50.734] INFO: Test took 4550ms.
[08:18:50.887] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:50.887] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 935369
[08:18:50.887] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[08:18:50.888] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:18:50.957] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:18:51.622] INFO: Expecting 41600 events.
[08:18:55.498] INFO: 41600 events read in total (3350ms).
[08:18:55.504] INFO: Test took 4547ms.
[08:18:55.657] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:55.658] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 934446
[08:18:55.658] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[08:18:55.658] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:18:55.727] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:18:56.392] INFO: Expecting 41600 events.
[08:19:00.252] INFO: 41600 events read in total (3334ms).
[08:19:00.258] INFO: Test took 4531ms.
[08:19:00.413] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:00.413] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 934648
[08:19:00.413] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[08:19:00.413] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:19:00.480] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:19:01.145] INFO: Expecting 41600 events.
[08:19:05.050] INFO: 41600 events read in total (3378ms).
[08:19:05.056] INFO: Test took 4576ms.
[08:19:05.210] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:05.210] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 935971
[08:19:05.210] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[08:19:05.211] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:19:05.278] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:19:05.943] INFO: Expecting 41600 events.
[08:19:09.823] INFO: 41600 events read in total (3353ms).
[08:19:09.829] INFO: Test took 4551ms.
[08:19:09.982] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:09.982] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 935395
[08:19:09.982] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[08:19:09.983] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:19:10.052] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:19:10.717] INFO: Expecting 41600 events.
[08:19:14.595] INFO: 41600 events read in total (3351ms).
[08:19:14.600] INFO: Test took 4549ms.
[08:19:14.753] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:14.753] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 935695
[08:19:14.753] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[08:19:14.753] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:19:14.823] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:19:15.488] INFO: Expecting 41600 events.
[08:19:19.375] INFO: 41600 events read in total (3360ms).
[08:19:19.380] INFO: Test took 4557ms.
[08:19:19.534] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:19.534] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 937363
[08:19:19.534] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[08:19:19.535] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:19:19.603] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:19:20.268] INFO: Expecting 41600 events.
[08:19:24.152] INFO: 41600 events read in total (3358ms).
[08:19:24.157] INFO: Test took 4554ms.
[08:19:24.312] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:24.312] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 935288
[08:19:24.312] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[08:19:24.312] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:19:24.380] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:19:25.045] INFO: Expecting 41600 events.
[08:19:28.930] INFO: 41600 events read in total (3359ms).
[08:19:28.936] INFO: Test took 4556ms.
[08:19:29.088] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:29.088] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 933133
[08:19:29.088] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[08:19:29.088] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:19:29.157] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:19:29.823] INFO: Expecting 41600 events.
[08:19:33.707] INFO: 41600 events read in total (3358ms).
[08:19:33.713] INFO: Test took 4555ms.
[08:19:33.868] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:33.869] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 934631
[08:19:33.869] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[08:19:33.869] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:19:33.937] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:19:34.602] INFO: Expecting 41600 events.
[08:19:38.499] INFO: 41600 events read in total (3370ms).
[08:19:38.505] INFO: Test took 4568ms.
[08:19:38.660] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:38.661] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 936677
[08:19:38.661] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[08:19:38.661] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:19:38.729] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:19:39.394] INFO: Expecting 41600 events.
[08:19:43.283] INFO: 41600 events read in total (3362ms).
[08:19:43.288] INFO: Test took 4559ms.
[08:19:43.445] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:43.445] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 936812
[08:19:43.445] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[08:19:43.445] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:19:43.514] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:19:44.179] INFO: Expecting 41600 events.
[08:19:48.050] INFO: 41600 events read in total (3345ms).
[08:19:48.055] INFO: Test took 4541ms.
[08:19:48.210] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:48.210] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 935018
[08:19:48.210] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[08:19:48.210] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:19:48.278] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:19:48.943] INFO: Expecting 41600 events.
[08:19:52.821] INFO: 41600 events read in total (3351ms).
[08:19:52.826] INFO: Test took 4548ms.
[08:19:52.977] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:52.977] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 934191
[08:19:52.977] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[08:19:52.978] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:19:53.047] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:19:53.712] INFO: Expecting 41600 events.
[08:19:57.484] INFO: 41600 events read in total (3246ms).
[08:19:57.490] INFO: Test took 4443ms.
[08:19:57.640] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:57.640] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 931279
[08:19:57.640] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[08:19:57.640] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:19:57.931] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 151 eff = 0.998558
[08:19:57.931] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 143 eff = 0.999087
[08:19:57.932] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 184 eff = 0.997404
[08:19:57.932] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 165 eff = 0.995986
[08:19:57.932] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 161 eff = 0.995264
[08:19:57.932] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 157 eff = 0.995529
[08:19:57.932] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 163 eff = 0.995361
[08:19:57.932] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 162 eff = 0.996178
[08:19:57.933] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 163 eff = 0.99613
[08:19:57.933] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 159 eff = 0.994615
[08:19:57.933] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 175 eff = 0.99488
[08:19:57.933] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 170 eff = 0.996154
[08:19:57.933] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 159 eff = 0.995769
[08:19:57.933] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 192 eff = 0.997308
[08:19:57.933] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 149 eff = 0.998894
[08:19:57.933] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 162 eff = 0.998726
[08:20:18.238] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[08:20:18.239] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[08:20:18.239] INFO: ----------------------------------------------------------------------
[08:20:18.239] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[08:20:18.239] INFO: ----------------------------------------------------------------------
[08:20:18.239] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[08:20:18.239] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[08:20:18.239] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[08:20:18.239] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[08:20:18.239] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[08:20:18.239] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[08:20:18.239] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[08:20:18.239] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[08:20:18.239] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[08:20:18.239] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[08:20:18.239] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[08:20:18.239] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[08:20:18.255] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[08:20:18.922] INFO: Expecting 208000 events.
[08:20:35.307] INFO: 208000 events read in total (15858ms).
[08:20:35.329] INFO: Test took 17074ms.
[08:20:36.096] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:36.096] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4358437
[08:20:36.096] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[08:20:36.097] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[08:20:36.615] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 2 0 0 0 0 0 0 0
[08:20:36.615] INFO: number of red-efficiency pixels: 303 219 475 768 841 854 803 702 702 897 794 801 778 507 235 239
[08:20:36.615] INFO: number of X-ray hits detected: 188523 127677 208377 318981 333227 334549 332234 294146 304873 350608 339899 311463 325129 218894 133251 170049
[08:20:36.615] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[08:20:36.615] INFO: number of Vcal hits detected: 207682 207772 207446 207110 206969 207015 207056 207224 207105 206934 207081 206969 207094 207420 207737 207748
[08:20:36.615] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.6 99.6 99.6 99.6 99.6 99.6 99.5 99.6 99.5 99.6 99.7 99.9 99.9
[08:20:36.615] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.5 99.5 99.5 99.6 99.6 99.5 99.6 99.5 99.6 99.7 99.9 99.9
[08:20:36.615] INFO: X-ray hit rate [MHz/cm2]: 55.3 37.4 61.1 93.5 97.7 98.1 97.4 86.2 89.4 102.8 99.6 91.3 95.3 64.2 39.1 49.8
[08:20:36.615] INFO: PixTestHighRate::doXPixelAlive() done
[08:20:36.661] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[08:20:36.661] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[08:20:36.661] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[08:20:36.661] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[08:20:36.661] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[08:20:36.661] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[08:20:36.661] INFO: PixTest:: pg_setup set to default.
[08:20:42.790] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[08:20:42.791] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 385.9mA
[08:20:42.792] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[08:20:42.792] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[08:20:42.792] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[08:20:42.792] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[08:20:42.792] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[08:20:42.792] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[08:20:42.792] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[08:20:42.792] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[08:20:42.792] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[08:20:42.793] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[08:20:42.793] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[08:20:42.793] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[08:20:42.871] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[08:20:42.871] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[08:20:42.880] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[08:20:42.880] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[08:20:42.880] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[08:20:42.880] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[08:20:42.880] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[08:20:42.880] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[08:20:42.883] QUIET: Connection to board 58 closed.
[08:20:42.962] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries