[17:43:53.011] INFO: *** Welcome to pxar ***
[17:43:53.011] INFO: *** Today: 2016/06/03
[17:43:53.034] INFO: *** Version: v1.9.0-805-geb36
[17:43:53.034] INFO: readRocDacs: data/mj108/dacParameters35_C0.dat .. data/mj108/dacParameters35_C15.dat
[17:43:53.035] INFO: readTbmDacs: data/mj108/tbmParameters_C0a.dat .. data/mj108/tbmParameters_C0b.dat
[17:43:53.035] INFO: readMaskFile: data/mj108/defaultMaskFile.dat
[17:43:53.035] INFO: readTrimFile: data/mj108/trimParameters35_C0.dat .. data/mj108/trimParameters35_C15.dat
[17:43:53.184] INFO: clk: 4
[17:43:53.184] INFO: ctr: 4
[17:43:53.184] INFO: sda: 19
[17:43:53.184] INFO: tin: 9
[17:43:53.184] INFO: level: 15
[17:43:53.184] INFO: triggerdelay: 0
[17:43:53.184] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:43:53.184] INFO: Log level: DEBUG
[17:43:53.195] QUIET: Connection to board DTB_WRPRHI opened.
[17:43:53.198] 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:
------------------------------------------------------
[17:43:53.201] INFO: RPC call hashes of host and DTB match: 398089610
[17:43:54.802] INFO: DUT info:
[17:43:54.802] INFO: The DUT currently contains the following objects:
[17:43:54.802] INFO: 2 TBM Cores tbm08c (2 ON)
[17:43:54.802] INFO: TBM Core alpha (0): 7 registers set
[17:43:54.802] INFO: TBM Core beta (1): 7 registers set
[17:43:54.802] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:43:54.802] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:43:54.802] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:43:54.802] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:43:54.802] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:43:54.802] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:43:54.802] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:43:54.802] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:43:54.802] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:43:54.802] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:43:54.803] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:43:54.804] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:43:54.805] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:43:54.807] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32776192
[17:43:54.807] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x262dc00
[17:43:54.807] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x25bbe60
[17:43:54.807] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f2955d94010
[17:43:54.807] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f295bfff510
[17:43:54.807] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32784384 fPxarMemory = 0x7f2955d94010
[17:43:54.808] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 390.7mA
[17:43:54.810] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[17:43:54.810] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[17:43:55.297] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2c04870
[17:43:55.359] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:43:55.360] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:43:55.360] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:43:55.365] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:43:55.365] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:43:55.366] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:43:55.376] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:43:55.376] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:43:55.391] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:43:55.391] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:43:55.401] INFO: PixTestCmd::init()
[17:43:55.410] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:43:55.410] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:43:55.410] INFO: readGainPedestalParameters data/mj108/phCalibrationFitErr35_C0.dat .. data/mj108/phCalibrationFitErr35_C15.dat
[17:43:55.654] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:43:55.654] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:43:55.669] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:43:55.675] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:43:55.675] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:43:55.675] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:43:55.699] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:43:55.699] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:43:55.699] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:43:55.699] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:43:55.699] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:43:55.705] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:43:55.705] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:43:55.727] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:43:55.727] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:43:55.727] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:43:55.727] INFO: readReadbackCal: data/mj108/readbackCal_C0.dat .. data/mj108/readbackCal_C15.dat
[17:43:55.737] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:43:55.745] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:43:55.745] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:43:55.745] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:43:55.745] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:43:55.765] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:43:55.765] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:44:01.823] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[17:44:05.399] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[17:44:07.791] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[17:44:07.791] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[17:44:07.791] INFO: ----------------------------------------------------------------------
[17:44:07.791] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[17:44:07.791] INFO: ----------------------------------------------------------------------
[17:44:08.764] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[17:44:20.025] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[17:44:20.029] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:44:44.892] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1109486 events.
[17:44:52.412] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1109486, pixels seen in all events: 6938559
[17:44:52.592] INFO: Resuming triggers.
[17:45:03.859] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[17:45:03.888] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:45:29.335] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1109892 events.
[17:45:37.423] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1109892, pixels seen in all events: 6932458
[17:45:37.643] INFO: Resuming triggers.
[17:45:48.905] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[17:45:48.941] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:46:14.240] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1109472 events.
[17:46:22.492] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1109472, pixels seen in all events: 6937611
[17:46:22.837] INFO: Resuming triggers.
[17:46:34.098] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[17:46:34.139] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:46:59.390] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1109384 events.
[17:47:07.777] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1109384, pixels seen in all events: 6938463
[17:47:07.995] INFO: Resuming triggers.
[17:47:19.256] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[17:47:19.283] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:47:44.095] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1109385 events.
[17:47:51.689] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1109385, pixels seen in all events: 6939058
[17:47:51.876] INFO: Resuming triggers.
[17:47:55.643] INFO: data taking finished, elapsed time: 60 seconds.
[17:47:55.839] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:48:04.218] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 371136 events.
[17:48:06.737] DEBUG: <PixTestXray.cc/processData:L809> # events read: 371136, pixels seen in all events: 2322405
[17:48:06.822] INFO: PixTest:: pg_setup set to default.
[17:48:06.861] INFO: PixTestXray::doPhRun() done
[17:53:16.599] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:53:16.600] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 393.9mA
[17:53:16.601] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[17:53:16.601] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[17:53:16.601] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:53:16.601] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:53:16.601] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:53:16.601] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:53:16.601] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:53:16.601] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:53:16.601] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:53:16.601] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:53:16.602] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:53:16.602] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:53:16.602] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:53:16.602] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:53:16.602] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:53:16.602] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:53:16.602] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:53:16.602] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:53:16.602] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:53:16.602] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:53:16.877] QUIET: Connection to board 58 closed.
[17:53:16.956] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[17:28:25.504] INFO: *** Welcome to pxar ***
[17:28:25.504] INFO: *** Today: 2016/06/03
[17:28:25.527] INFO: *** Version: v1.9.0-805-geb36
[17:28:25.527] INFO: readRocDacs: data/mj108/dacParameters35_C0.dat .. data/mj108/dacParameters35_C15.dat
[17:28:25.528] INFO: readTbmDacs: data/mj108/tbmParameters_C0a.dat .. data/mj108/tbmParameters_C0b.dat
[17:28:25.528] INFO: readMaskFile: data/mj108/defaultMaskFile.dat
[17:28:25.528] INFO: readTrimFile: data/mj108/trimParameters35_C0.dat .. data/mj108/trimParameters35_C15.dat
[17:28:25.677] INFO: clk: 4
[17:28:25.677] INFO: ctr: 4
[17:28:25.677] INFO: sda: 19
[17:28:25.677] INFO: tin: 9
[17:28:25.677] INFO: level: 15
[17:28:25.677] INFO: triggerdelay: 0
[17:28:25.677] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:28:25.677] INFO: Log level: DEBUG
[17:28:25.690] QUIET: Connection to board DTB_WRPRHI opened.
[17:28:25.693] 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:
------------------------------------------------------
[17:28:25.696] INFO: RPC call hashes of host and DTB match: 398089610
[17:28:27.295] INFO: DUT info:
[17:28:27.295] INFO: The DUT currently contains the following objects:
[17:28:27.295] INFO: 2 TBM Cores tbm08c (2 ON)
[17:28:27.295] INFO: TBM Core alpha (0): 7 registers set
[17:28:27.295] INFO: TBM Core beta (1): 7 registers set
[17:28:27.295] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:28:27.295] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.295] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.295] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.295] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.295] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.295] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.296] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.296] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.296] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.296] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.296] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.296] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.296] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.296] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.296] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.296] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:28:27.296] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:28:27.297] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:28:27.298] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:28:27.301] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35016704
[17:28:27.301] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x127dbe0
[17:28:27.301] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x101fe60
[17:28:27.301] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fb065d94010
[17:28:27.301] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fb06bfff510
[17:28:27.301] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35024896 fPxarMemory = 0x7fb065d94010
[17:28:27.302] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 390.7mA
[17:28:27.303] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[17:28:27.303] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[17:28:27.792] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1668850
[17:28:27.853] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:28:27.853] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:28:27.853] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:28:27.859] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:28:27.859] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:28:27.859] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:28:27.869] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:28:27.869] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:28:27.889] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:28:27.889] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:28:27.899] INFO: PixTestCmd::init()
[17:28:27.905] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:28:27.905] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:28:27.905] INFO: readGainPedestalParameters data/mj108/phCalibrationFitErr35_C0.dat .. data/mj108/phCalibrationFitErr35_C15.dat
[17:28:28.149] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:28:28.149] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:28:28.164] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:28:28.170] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:28:28.170] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:28:28.170] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:28:28.195] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:28:28.195] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:28:28.195] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:28:28.195] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:28:28.195] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:28:28.201] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:28:28.201] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:28:28.217] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:28:28.218] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:28:28.218] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:28:28.218] INFO: readReadbackCal: data/mj108/readbackCal_C0.dat .. data/mj108/readbackCal_C15.dat
[17:28:28.227] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:28:28.235] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:28:28.235] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:28:28.235] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:28:28.235] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:28:28.254] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:28:28.254] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:28:43.079] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[17:28:46.127] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[17:28:46.727] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[17:28:46.727] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[17:28:46.727] INFO: ----------------------------------------------------------------------
[17:28:46.727] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[17:28:46.727] INFO: ----------------------------------------------------------------------
[17:28:47.699] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[17:28:55.119] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[17:28:55.123] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:29:17.974] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 731086 events.
[17:29:30.303] DEBUG: <PixTestXray.cc/processData:L809> # events read: 731086, pixels seen in all events: 11481089
[17:29:30.533] INFO: Resuming triggers.
[17:29:37.961] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[17:29:37.990] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:30:00.650] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 731733 events.
[17:30:12.988] DEBUG: <PixTestXray.cc/processData:L809> # events read: 731733, pixels seen in all events: 11471534
[17:30:13.197] INFO: Resuming triggers.
[17:30:20.623] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[17:30:20.653] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:30:43.282] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 731497 events.
[17:30:55.883] DEBUG: <PixTestXray.cc/processData:L809> # events read: 731497, pixels seen in all events: 11473971
[17:30:56.171] INFO: Resuming triggers.
[17:31:03.602] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[17:31:03.646] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:31:26.963] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 731939 events.
[17:31:39.805] DEBUG: <PixTestXray.cc/processData:L809> # events read: 731939, pixels seen in all events: 11469141
[17:31:40.008] INFO: Resuming triggers.
[17:31:47.436] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[17:31:47.472] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:32:10.589] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 731737 events.
[17:32:23.598] DEBUG: <PixTestXray.cc/processData:L809> # events read: 731737, pixels seen in all events: 11470915
[17:32:23.856] INFO: Resuming triggers.
[17:32:31.289] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[17:32:31.318] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:32:54.903] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 732197 events.
[17:33:07.585] DEBUG: <PixTestXray.cc/processData:L809> # events read: 732197, pixels seen in all events: 11465423
[17:33:07.778] INFO: Resuming triggers.
[17:33:15.212] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[17:33:15.274] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:33:38.085] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 732397 events.
[17:33:51.514] DEBUG: <PixTestXray.cc/processData:L809> # events read: 732397, pixels seen in all events: 11462570
[17:33:51.729] INFO: Resuming triggers.
[17:33:59.161] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[17:33:59.215] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:34:22.916] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 732151 events.
[17:34:35.789] DEBUG: <PixTestXray.cc/processData:L809> # events read: 732151, pixels seen in all events: 11465857
[17:34:36.032] INFO: Resuming triggers.
[17:34:36.681] INFO: data taking finished, elapsed time: 60 seconds.
[17:34:36.878] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:34:39.027] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 63924 events.
[17:34:40.110] DEBUG: <PixTestXray.cc/processData:L809> # events read: 63924, pixels seen in all events: 1001138
[17:34:40.149] INFO: PixTest:: pg_setup set to default.
[17:34:40.182] INFO: PixTestXray::doPhRun() done
[17:42:13.071] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:42:13.072] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 393.9mA
[17:42:13.073] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[17:42:13.073] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[17:42:13.073] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:42:13.073] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:42:13.073] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:42:13.073] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:42:13.073] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:42:13.073] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:42:13.073] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:42:13.073] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:42:13.074] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:42:13.074] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:42:13.074] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:42:13.074] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:42:13.074] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:42:13.074] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:42:13.074] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:42:13.074] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:42:13.074] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:42:13.074] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:42:13.385] QUIET: Connection to board 58 closed.
[17:42:13.465] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[17:19:14.128] INFO: *** Welcome to pxar ***
[17:19:14.128] INFO: *** Today: 2016/06/03
[17:19:14.150] INFO: *** Version: v1.9.0-805-geb36
[17:19:14.151] INFO: readRocDacs: data/mj108/dacParameters35_C0.dat .. data/mj108/dacParameters35_C15.dat
[17:19:14.152] INFO: readTbmDacs: data/mj108/tbmParameters_C0a.dat .. data/mj108/tbmParameters_C0b.dat
[17:19:14.152] INFO: readMaskFile: data/mj108/defaultMaskFile.dat
[17:19:14.152] INFO: readTrimFile: data/mj108/trimParameters35_C0.dat .. data/mj108/trimParameters35_C15.dat
[17:19:14.301] INFO: clk: 4
[17:19:14.301] INFO: ctr: 4
[17:19:14.301] INFO: sda: 19
[17:19:14.301] INFO: tin: 9
[17:19:14.301] INFO: level: 15
[17:19:14.301] INFO: triggerdelay: 0
[17:19:14.301] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:19:14.302] INFO: Log level: DEBUG
[17:19:14.314] QUIET: Connection to board DTB_WRPRHI opened.
[17:19:14.317] 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:
------------------------------------------------------
[17:19:14.320] INFO: RPC call hashes of host and DTB match: 398089610
[17:19:15.919] INFO: DUT info:
[17:19:15.919] INFO: The DUT currently contains the following objects:
[17:19:15.919] INFO: 2 TBM Cores tbm08c (2 ON)
[17:19:15.920] INFO: TBM Core alpha (0): 7 registers set
[17:19:15.920] INFO: TBM Core beta (1): 7 registers set
[17:19:15.920] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:19:15.920] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:19:15.920] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:19:15.921] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:19:15.922] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:19:15.923] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:19:15.923] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:19:15.923] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:19:15.923] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:19:15.923] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:19:15.923] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:19:15.923] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:19:15.923] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:19:15.925] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33583104
[17:19:15.925] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x17d6be0
[17:19:15.925] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1578e60
[17:19:15.925] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f78f5d94010
[17:19:15.925] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f78fbfff510
[17:19:15.925] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33591296 fPxarMemory = 0x7f78f5d94010
[17:19:15.926] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 390.7mA
[17:19:15.927] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[17:19:15.927] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[17:19:16.416] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1bc1920
[17:19:16.478] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:19:16.478] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:19:16.478] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:19:16.484] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:19:16.484] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:19:16.484] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:19:16.506] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:19:16.506] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:19:16.518] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:19:16.518] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:19:16.534] INFO: PixTestCmd::init()
[17:19:16.546] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:19:16.547] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:19:16.547] INFO: readGainPedestalParameters data/mj108/phCalibrationFitErr35_C0.dat .. data/mj108/phCalibrationFitErr35_C15.dat
[17:19:16.790] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:19:16.790] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:19:16.806] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:19:16.812] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:19:16.812] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:19:16.812] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:19:16.834] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:19:16.834] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:19:16.834] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:19:16.834] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:19:16.834] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:19:16.841] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:19:16.841] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:19:16.864] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:19:16.864] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:19:16.864] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:19:16.864] INFO: readReadbackCal: data/mj108/readbackCal_C0.dat .. data/mj108/readbackCal_C15.dat
[17:19:16.876] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:19:16.892] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:19:16.892] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:19:16.892] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:19:16.892] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:19:16.905] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:19:16.905] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:19:24.098] INFO: ######################################################################
[17:19:24.098] INFO: PixTestAlive::doTest()
[17:19:24.098] INFO: ######################################################################
[17:19:24.102] INFO: ----------------------------------------------------------------------
[17:19:24.102] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:19:24.102] INFO: ----------------------------------------------------------------------
[17:19:24.104] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:19:24.479] INFO: Expecting 41600 events.
[17:19:28.729] INFO: 41600 events read in total (3532ms).
[17:19:28.877] INFO: Test took 4773ms.
[17:19:28.887] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:19:28.887] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[17:19:28.887] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[17:19:29.155] INFO: PixTestAlive::aliveTest() done
[17:19:29.155] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 1
[17:19:29.156] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 25 15 26 55 27 45 37 42 42 43 40 37 48 29 15 22
[17:19:29.179] INFO: ----------------------------------------------------------------------
[17:19:29.179] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:19:29.179] INFO: ----------------------------------------------------------------------
[17:19:29.181] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:19:29.555] INFO: Expecting 41600 events.
[17:19:32.605] INFO: 41600 events read in total (2334ms).
[17:19:32.605] INFO: Test took 3424ms.
[17:19:32.605] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:19:32.605] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[17:19:32.605] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[17:19:32.606] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:19:33.030] INFO: PixTestAlive::maskTest() done
[17:19:33.030] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:19:33.049] INFO: ----------------------------------------------------------------------
[17:19:33.049] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:19:33.049] INFO: ----------------------------------------------------------------------
[17:19:33.051] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:19:33.425] INFO: Expecting 41600 events.
[17:19:37.679] INFO: 41600 events read in total (3539ms).
[17:19:37.680] INFO: Test took 4629ms.
[17:19:37.691] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:19:37.691] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[17:19:37.691] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[17:19:38.062] INFO: PixTestAlive::addressDecodingTest() done
[17:19:38.062] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:19:38.062] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[17:19:39.567] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[17:19:39.567] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[17:19:39.567] INFO: ----------------------------------------------------------------------
[17:19:39.567] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:19:39.567] INFO: ----------------------------------------------------------------------
[17:19:39.706] INFO: Expecting 768 events.
[17:19:40.838] INFO: 768 events read in total (417ms).
[17:19:40.838] INFO: Test took 1265ms.
[17:19:40.844] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:19:41.642] INFO: Expecting 41600 events.
[17:19:44.945] INFO: 41600 events read in total (2777ms).
[17:19:44.950] INFO: Test took 4106ms.
[17:19:45.046] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:19:45.046] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 528325
[17:19:45.046] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[17:19:45.046] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:19:45.087] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:19:45.753] INFO: Expecting 41600 events.
[17:19:49.129] INFO: 41600 events read in total (2851ms).
[17:19:49.136] INFO: Test took 4049ms.
[17:19:49.230] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:19:49.230] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 536399
[17:19:49.230] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[17:19:49.230] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:19:49.273] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:19:49.938] INFO: Expecting 41600 events.
[17:19:53.344] INFO: 41600 events read in total (2879ms).
[17:19:53.350] INFO: Test took 4077ms.
[17:19:53.444] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:19:53.444] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537141
[17:19:53.444] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[17:19:53.444] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:19:53.487] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:19:54.152] INFO: Expecting 41600 events.
[17:19:57.566] INFO: 41600 events read in total (2887ms).
[17:19:57.572] INFO: Test took 4085ms.
[17:19:57.665] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:19:57.665] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 535249
[17:19:57.665] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[17:19:57.666] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:19:57.708] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:19:58.374] INFO: Expecting 41600 events.
[17:20:01.782] INFO: 41600 events read in total (2882ms).
[17:20:01.787] INFO: Test took 4079ms.
[17:20:01.881] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:01.881] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 536925
[17:20:01.881] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[17:20:01.881] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:01.924] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:02.589] INFO: Expecting 41600 events.
[17:20:06.008] INFO: 41600 events read in total (2892ms).
[17:20:06.012] INFO: Test took 4088ms.
[17:20:06.104] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:06.104] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537409
[17:20:06.104] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[17:20:06.104] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:06.147] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:06.812] INFO: Expecting 41600 events.
[17:20:10.235] INFO: 41600 events read in total (2896ms).
[17:20:10.239] INFO: Test took 4092ms.
[17:20:10.331] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:10.331] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 536904
[17:20:10.331] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[17:20:10.331] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:10.375] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:11.039] INFO: Expecting 41600 events.
[17:20:14.474] INFO: 41600 events read in total (2908ms).
[17:20:14.478] INFO: Test took 4103ms.
[17:20:14.570] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:14.570] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537659
[17:20:14.570] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[17:20:14.571] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:14.615] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:15.280] INFO: Expecting 41600 events.
[17:20:18.706] INFO: 41600 events read in total (2900ms).
[17:20:18.710] INFO: Test took 4095ms.
[17:20:18.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:18.803] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 536035
[17:20:18.803] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[17:20:18.804] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:18.847] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:19.512] INFO: Expecting 41600 events.
[17:20:22.955] INFO: 41600 events read in total (2917ms).
[17:20:22.959] INFO: Test took 4112ms.
[17:20:23.051] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:23.051] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537762
[17:20:23.051] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[17:20:23.051] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:23.095] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:23.760] INFO: Expecting 41600 events.
[17:20:27.198] INFO: 41600 events read in total (2911ms).
[17:20:27.202] INFO: Test took 4107ms.
[17:20:27.324] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:27.324] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 535802
[17:20:27.324] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[17:20:27.325] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:27.369] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:28.034] INFO: Expecting 41600 events.
[17:20:31.468] INFO: 41600 events read in total (2907ms).
[17:20:31.472] INFO: Test took 4103ms.
[17:20:31.564] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:31.564] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537737
[17:20:31.564] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[17:20:31.565] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:31.609] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:32.274] INFO: Expecting 41600 events.
[17:20:35.713] INFO: 41600 events read in total (2913ms).
[17:20:35.717] INFO: Test took 4108ms.
[17:20:35.810] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:35.810] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 538662
[17:20:35.810] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[17:20:35.810] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:35.855] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:36.519] INFO: Expecting 41600 events.
[17:20:39.944] INFO: 41600 events read in total (2898ms).
[17:20:39.948] INFO: Test took 4093ms.
[17:20:40.041] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:40.041] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 538965
[17:20:40.041] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[17:20:40.041] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:40.085] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:40.750] INFO: Expecting 41600 events.
[17:20:44.172] INFO: 41600 events read in total (2895ms).
[17:20:44.176] INFO: Test took 4091ms.
[17:20:44.268] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:44.268] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 536603
[17:20:44.268] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[17:20:44.269] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:44.314] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:44.979] INFO: Expecting 41600 events.
[17:20:48.403] INFO: 41600 events read in total (2897ms).
[17:20:48.407] INFO: Test took 4093ms.
[17:20:48.499] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:48.499] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 537583
[17:20:48.499] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[17:20:48.500] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:48.544] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:49.209] INFO: Expecting 41600 events.
[17:20:52.638] INFO: 41600 events read in total (2902ms).
[17:20:52.642] INFO: Test took 4098ms.
[17:20:52.734] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:52.734] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 538034
[17:20:52.734] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[17:20:52.735] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:52.779] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:53.445] INFO: Expecting 41600 events.
[17:20:56.868] INFO: 41600 events read in total (2897ms).
[17:20:56.872] INFO: Test took 4093ms.
[17:20:56.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:20:56.963] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 536544
[17:20:56.963] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[17:20:56.964] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:20:57.008] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:20:57.674] INFO: Expecting 41600 events.
[17:21:01.052] INFO: 41600 events read in total (2852ms).
[17:21:01.058] INFO: Test took 4050ms.
[17:21:01.151] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:01.151] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 536921
[17:21:01.151] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[17:21:01.151] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:01.196] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:01.861] INFO: Expecting 41600 events.
[17:21:05.065] INFO: 41600 events read in total (2677ms).
[17:21:05.069] INFO: Test took 3873ms.
[17:21:05.158] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:05.158] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 527721
[17:21:05.158] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[17:21:05.158] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:05.422] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 173 eff = 0.999591
[17:21:05.422] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 161 eff = 0.999639
[17:21:05.423] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 170 eff = 0.999279
[17:21:05.423] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 166 eff = 0.998558
[17:21:05.423] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 170 eff = 0.998654
[17:21:05.423] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 182 eff = 0.998582
[17:21:05.423] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 164 eff = 0.99875
[17:21:05.424] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 168 eff = 0.99875
[17:21:05.424] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 178 eff = 0.999063
[17:21:05.424] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 157 eff = 0.998654
[17:21:05.424] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 157 eff = 0.998462
[17:21:05.424] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 145 eff = 0.998486
[17:21:05.424] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 158 eff = 0.998774
[17:21:05.424] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 144 eff = 0.999327
[17:21:05.424] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 151 eff = 0.99988
[17:21:05.425] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 158 eff = 0.999375
[17:21:24.815] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[17:21:24.815] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[17:21:24.815] INFO: ----------------------------------------------------------------------
[17:21:24.815] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:21:24.815] INFO: ----------------------------------------------------------------------
[17:21:24.815] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[17:21:24.815] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[17:21:24.815] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[17:21:24.815] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[17:21:24.816] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[17:21:24.816] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[17:21:24.816] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[17:21:24.816] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[17:21:24.816] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[17:21:24.816] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[17:21:24.816] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[17:21:24.816] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[17:21:24.831] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:21:25.497] INFO: Expecting 208000 events.
[17:21:38.635] INFO: 208000 events read in total (12612ms).
[17:21:38.649] INFO: Test took 13818ms.
[17:21:39.150] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:21:39.150] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2401406
[17:21:39.150] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[17:21:39.151] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:21:39.540] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 1
[17:21:39.540] INFO: number of red-efficiency pixels: 117 100 167 242 311 313 272 283 273 354 349 344 263 184 82 125
[17:21:39.540] INFO: number of X-ray hits detected: 97640 70481 117289 171985 183057 181581 178255 161867 170731 190386 187331 174563 181541 115778 67201 85162
[17:21:39.540] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:21:39.540] INFO: number of Vcal hits detected: 207880 207899 207829 207702 207674 207673 207718 207705 207715 207625 207628 207635 207728 207803 207917 207823
[17:21:39.540] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 100.0 99.9
[17:21:39.540] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.8 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 100.0 99.9
[17:21:39.540] INFO: X-ray hit rate [MHz/cm2]: 28.6 20.7 34.4 50.4 53.7 53.2 52.2 47.4 50.0 55.8 54.9 51.2 53.2 33.9 19.7 25.0
[17:21:39.540] INFO: PixTestHighRate::doXPixelAlive() done
[17:21:39.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[17:21:39.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[17:21:39.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[17:21:39.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[17:21:39.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[17:21:39.585] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[17:21:39.585] INFO: PixTest:: pg_setup set to default.
[17:23:56.039] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:23:56.040] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 393.9mA
[17:23:56.040] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[17:23:56.041] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[17:23:56.041] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:23:56.041] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:23:56.041] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:23:56.041] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:23:56.041] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:23:56.041] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:23:56.041] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:23:56.041] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:23:56.041] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:23:56.041] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:23:56.120] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:23:56.120] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:23:56.129] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:23:56.129] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:23:56.129] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:23:56.129] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:23:56.129] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:23:56.129] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:23:56.132] QUIET: Connection to board 58 closed.
[17:23:56.212] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[17:15:50.814] INFO: *** Welcome to pxar ***
[17:15:50.814] INFO: *** Today: 2016/06/03
[17:15:50.836] INFO: *** Version: v1.9.0-805-geb36
[17:15:50.836] INFO: readRocDacs: data/mj108/dacParameters35_C0.dat .. data/mj108/dacParameters35_C15.dat
[17:15:50.837] INFO: readTbmDacs: data/mj108/tbmParameters_C0a.dat .. data/mj108/tbmParameters_C0b.dat
[17:15:50.837] INFO: readMaskFile: data/mj108/defaultMaskFile.dat
[17:15:50.838] INFO: readTrimFile: data/mj108/trimParameters35_C0.dat .. data/mj108/trimParameters35_C15.dat
[17:15:50.990] INFO: clk: 4
[17:15:50.990] INFO: ctr: 4
[17:15:50.990] INFO: sda: 19
[17:15:50.990] INFO: tin: 9
[17:15:50.990] INFO: level: 15
[17:15:50.990] INFO: triggerdelay: 0
[17:15:50.990] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:15:50.990] INFO: Log level: DEBUG
[17:15:51.002] QUIET: Connection to board DTB_WRPRHI opened.
[17:15:51.005] 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:
------------------------------------------------------
[17:15:51.007] INFO: RPC call hashes of host and DTB match: 398089610
[17:15:52.607] INFO: DUT info:
[17:15:52.607] INFO: The DUT currently contains the following objects:
[17:15:52.607] INFO: 2 TBM Cores tbm08c (2 ON)
[17:15:52.607] INFO: TBM Core alpha (0): 7 registers set
[17:15:52.607] INFO: TBM Core beta (1): 7 registers set
[17:15:52.607] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:15:52.607] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.607] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.607] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.607] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.607] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.607] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.607] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.607] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.607] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.607] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.607] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.607] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.607] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.607] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.607] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.607] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:15:52.608] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:15:52.609] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:15:52.610] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:15:52.612] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31399936
[17:15:52.612] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x291bbe0
[17:15:52.612] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x26bde60
[17:15:52.612] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f40e1d94010
[17:15:52.612] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f40e7fff510
[17:15:52.612] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31408128 fPxarMemory = 0x7f40e1d94010
[17:15:52.613] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 390.7mA
[17:15:52.615] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[17:15:52.615] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[17:15:53.105] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2d06860
[17:15:53.167] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:15:53.167] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:15:53.167] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:15:53.173] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:15:53.173] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:15:53.173] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:15:53.181] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:15:53.181] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:15:53.196] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:15:53.196] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:15:53.229] INFO: PixTestCmd::init()
[17:15:53.239] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:15:53.240] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:15:53.240] INFO: readGainPedestalParameters data/mj108/phCalibrationFitErr35_C0.dat .. data/mj108/phCalibrationFitErr35_C15.dat
[17:15:53.483] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:15:53.483] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:15:53.497] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:15:53.503] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:15:53.503] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:15:53.503] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:15:53.527] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:15:53.528] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:15:53.528] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:15:53.528] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:15:53.528] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:15:53.536] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:15:53.536] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:15:53.550] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:15:53.550] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:15:53.550] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:15:53.550] INFO: readReadbackCal: data/mj108/readbackCal_C0.dat .. data/mj108/readbackCal_C15.dat
[17:15:53.561] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:15:53.569] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:15:53.569] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:15:53.569] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:15:53.569] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:15:53.588] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:15:53.588] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:15:59.378] INFO: ######################################################################
[17:15:59.378] INFO: PixTestAlive::doTest()
[17:15:59.378] INFO: ######################################################################
[17:15:59.382] INFO: ----------------------------------------------------------------------
[17:15:59.382] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:15:59.382] INFO: ----------------------------------------------------------------------
[17:15:59.384] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:15:59.759] INFO: Expecting 41600 events.
[17:16:04.022] INFO: 41600 events read in total (3545ms).
[17:16:04.169] INFO: Test took 4785ms.
[17:16:04.179] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:04.179] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[17:16:04.179] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[17:16:04.448] INFO: PixTestAlive::aliveTest() done
[17:16:04.448] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 1
[17:16:04.448] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 46 27 33 67 64 59 67 67 67 73 70 76 76 58 22 33
[17:16:04.469] INFO: ----------------------------------------------------------------------
[17:16:04.469] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:16:04.469] INFO: ----------------------------------------------------------------------
[17:16:04.471] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:16:04.845] INFO: Expecting 41600 events.
[17:16:07.898] INFO: 41600 events read in total (2337ms).
[17:16:07.899] INFO: Test took 3428ms.
[17:16:07.899] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:07.899] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[17:16:07.899] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[17:16:07.899] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:16:08.322] INFO: PixTestAlive::maskTest() done
[17:16:08.322] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:16:08.341] INFO: ----------------------------------------------------------------------
[17:16:08.341] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:16:08.341] INFO: ----------------------------------------------------------------------
[17:16:08.343] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:16:08.717] INFO: Expecting 41600 events.
[17:16:12.970] INFO: 41600 events read in total (3537ms).
[17:16:12.972] INFO: Test took 4629ms.
[17:16:12.983] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:12.983] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[17:16:12.983] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[17:16:13.354] INFO: PixTestAlive::addressDecodingTest() done
[17:16:13.354] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:16:13.354] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[17:16:20.655] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[17:16:20.655] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[17:16:20.655] INFO: ----------------------------------------------------------------------
[17:16:20.655] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:16:20.655] INFO: ----------------------------------------------------------------------
[17:16:20.794] INFO: Expecting 768 events.
[17:16:21.927] INFO: 768 events read in total (418ms).
[17:16:21.927] INFO: Test took 1266ms.
[17:16:21.933] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:16:22.731] INFO: Expecting 41600 events.
[17:16:26.360] INFO: 41600 events read in total (3103ms).
[17:16:26.365] INFO: Test took 4432ms.
[17:16:26.502] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:26.502] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 795453
[17:16:26.502] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[17:16:26.503] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:16:26.561] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:16:27.227] INFO: Expecting 41600 events.
[17:16:30.928] INFO: 41600 events read in total (3174ms).
[17:16:30.933] INFO: Test took 4372ms.
[17:16:31.066] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:31.066] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 802363
[17:16:31.066] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[17:16:31.066] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:16:31.128] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:16:31.794] INFO: Expecting 41600 events.
[17:16:35.499] INFO: 41600 events read in total (3180ms).
[17:16:35.505] INFO: Test took 4377ms.
[17:16:35.636] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:35.636] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 802896
[17:16:35.636] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[17:16:35.637] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:16:35.698] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:16:36.363] INFO: Expecting 41600 events.
[17:16:40.096] INFO: 41600 events read in total (3206ms).
[17:16:40.101] INFO: Test took 4403ms.
[17:16:40.235] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:40.235] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 803520
[17:16:40.235] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[17:16:40.235] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:16:40.295] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:16:40.961] INFO: Expecting 41600 events.
[17:16:44.681] INFO: 41600 events read in total (3194ms).
[17:16:44.686] INFO: Test took 4390ms.
[17:16:44.818] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:44.818] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 802472
[17:16:44.818] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[17:16:44.818] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:16:44.880] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:16:45.546] INFO: Expecting 41600 events.
[17:16:49.265] INFO: 41600 events read in total (3193ms).
[17:16:49.270] INFO: Test took 4390ms.
[17:16:49.401] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:49.401] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 803351
[17:16:49.401] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[17:16:49.401] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:16:49.462] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:16:50.128] INFO: Expecting 41600 events.
[17:16:53.858] INFO: 41600 events read in total (3204ms).
[17:16:53.863] INFO: Test took 4401ms.
[17:16:53.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:53.994] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 804647
[17:16:53.994] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[17:16:53.995] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:16:54.056] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:16:54.720] INFO: Expecting 41600 events.
[17:16:58.468] INFO: 41600 events read in total (3221ms).
[17:16:58.473] INFO: Test took 4417ms.
[17:16:58.608] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:58.608] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 804422
[17:16:58.608] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[17:16:58.608] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:16:58.669] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:16:59.334] INFO: Expecting 41600 events.
[17:17:03.077] INFO: 41600 events read in total (3216ms).
[17:17:03.082] INFO: Test took 4413ms.
[17:17:03.215] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:03.215] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 803660
[17:17:03.215] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[17:17:03.215] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:03.277] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:03.942] INFO: Expecting 41600 events.
[17:17:07.699] INFO: 41600 events read in total (3231ms).
[17:17:07.704] INFO: Test took 4427ms.
[17:17:07.835] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:07.835] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 805774
[17:17:07.835] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[17:17:07.836] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:07.898] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:08.563] INFO: Expecting 41600 events.
[17:17:12.304] INFO: 41600 events read in total (3214ms).
[17:17:12.309] INFO: Test took 4411ms.
[17:17:12.439] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:12.439] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 805008
[17:17:12.439] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[17:17:12.440] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:12.502] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:13.167] INFO: Expecting 41600 events.
[17:17:16.913] INFO: 41600 events read in total (3219ms).
[17:17:16.918] INFO: Test took 4416ms.
[17:17:17.050] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:17.050] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 805529
[17:17:17.050] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[17:17:17.050] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:17.112] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:17.778] INFO: Expecting 41600 events.
[17:17:21.521] INFO: 41600 events read in total (3217ms).
[17:17:21.526] INFO: Test took 4414ms.
[17:17:21.660] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:21.660] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 803839
[17:17:21.660] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[17:17:21.660] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:21.722] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:22.387] INFO: Expecting 41600 events.
[17:17:26.129] INFO: 41600 events read in total (3215ms).
[17:17:26.134] INFO: Test took 4412ms.
[17:17:26.267] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:26.267] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 804249
[17:17:26.268] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[17:17:26.268] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:26.330] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:26.995] INFO: Expecting 41600 events.
[17:17:30.742] INFO: 41600 events read in total (3220ms).
[17:17:30.748] INFO: Test took 4418ms.
[17:17:30.878] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:30.878] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 804303
[17:17:30.878] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[17:17:30.878] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:30.940] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:31.605] INFO: Expecting 41600 events.
[17:17:35.358] INFO: 41600 events read in total (3226ms).
[17:17:35.363] INFO: Test took 4423ms.
[17:17:35.499] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:35.499] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 803413
[17:17:35.499] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[17:17:35.499] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:35.561] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:36.227] INFO: Expecting 41600 events.
[17:17:39.966] INFO: 41600 events read in total (3213ms).
[17:17:39.971] INFO: Test took 4410ms.
[17:17:40.103] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:40.103] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 802417
[17:17:40.103] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[17:17:40.103] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:40.165] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:40.830] INFO: Expecting 41600 events.
[17:17:44.556] INFO: 41600 events read in total (3199ms).
[17:17:44.561] INFO: Test took 4396ms.
[17:17:44.697] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:44.697] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 803905
[17:17:44.697] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[17:17:44.697] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:44.759] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:45.424] INFO: Expecting 41600 events.
[17:17:49.121] INFO: 41600 events read in total (3170ms).
[17:17:49.126] INFO: Test took 4367ms.
[17:17:49.256] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:49.256] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 800539
[17:17:49.256] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[17:17:49.257] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:49.320] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:17:49.985] INFO: Expecting 41600 events.
[17:17:53.495] INFO: 41600 events read in total (2983ms).
[17:17:53.500] INFO: Test took 4180ms.
[17:17:53.631] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:53.631] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 795119
[17:17:53.631] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[17:17:53.631] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:17:53.914] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 166 eff = 0.999159
[17:17:53.914] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 177 eff = 0.999327
[17:17:53.914] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 164 eff = 0.998413
[17:17:53.914] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 172 eff = 0.996875
[17:17:53.915] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 171 eff = 0.996418
[17:17:53.915] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 188 eff = 0.996923
[17:17:53.915] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 162 eff = 0.997091
[17:17:53.915] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 173 eff = 0.997764
[17:17:53.916] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 187 eff = 0.997188
[17:17:53.916] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 164 eff = 0.996346
[17:17:53.916] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 165 eff = 0.996298
[17:17:53.916] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 173 eff = 0.996923
[17:17:53.917] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 155 eff = 0.996779
[17:17:53.917] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 158 eff = 0.99851
[17:17:53.917] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 153 eff = 0.999423
[17:17:53.917] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 159 eff = 0.99899
[17:18:00.975] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[17:18:00.975] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[17:18:00.975] INFO: ----------------------------------------------------------------------
[17:18:00.975] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:18:00.975] INFO: ----------------------------------------------------------------------
[17:18:00.975] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[17:18:00.975] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[17:18:00.975] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[17:18:00.975] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[17:18:00.975] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[17:18:00.975] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[17:18:00.975] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[17:18:00.975] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[17:18:00.975] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[17:18:00.975] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[17:18:00.976] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[17:18:00.976] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[17:18:00.991] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:18:01.657] INFO: Expecting 208000 events.
[17:18:17.009] INFO: 208000 events read in total (14826ms).
[17:18:17.028] INFO: Test took 16037ms.
[17:18:17.720] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:18:17.721] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3714084
[17:18:17.721] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[17:18:17.721] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:18:18.198] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 1
[17:18:18.198] INFO: number of red-efficiency pixels: 237 133 335 562 670 631 563 520 546 669 653 585 638 320 133 232
[17:18:18.198] INFO: number of X-ray hits detected: 152831 110214 182292 268964 286321 283671 278551 252155 267010 298488 292805 271646 283266 182026 104430 132856
[17:18:18.198] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:18:18.198] INFO: number of Vcal hits detected: 207750 207864 207631 207257 207251 207282 207384 207425 207402 207255 207278 207358 207295 207652 207863 207706
[17:18:18.198] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.7 99.7 99.7 99.7 99.7 99.7 99.7 99.7 99.8 99.9 99.9
[17:18:18.198] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.6 99.6 99.7 99.7 99.7 99.7 99.6 99.7 99.7 99.7 99.8 99.9 99.9
[17:18:18.198] INFO: X-ray hit rate [MHz/cm2]: 44.8 32.3 53.4 78.8 83.9 83.1 81.6 73.9 78.3 87.5 85.8 79.6 83.0 53.4 30.6 38.9
[17:18:18.198] INFO: PixTestHighRate::doXPixelAlive() done
[17:18:18.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[17:18:18.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[17:18:18.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[17:18:18.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[17:18:18.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[17:18:18.243] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[17:18:18.243] INFO: PixTest:: pg_setup set to default.
[17:19:03.423] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:19:03.424] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 393.9mA
[17:19:03.424] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[17:19:03.424] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[17:19:03.424] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:19:03.424] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:19:03.425] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:19:03.425] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:19:03.425] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:19:03.425] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:19:03.425] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:19:03.425] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:19:03.425] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:19:03.425] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:19:03.503] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:19:03.503] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:19:03.512] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:19:03.513] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:19:03.513] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:19:03.513] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:19:03.513] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:19:03.513] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:19:03.515] QUIET: Connection to board 58 closed.
[17:19:03.594] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[17:11:25.485] INFO: *** Welcome to pxar ***
[17:11:25.485] INFO: *** Today: 2016/06/03
[17:11:25.508] INFO: *** Version: v1.9.0-805-geb36
[17:11:25.508] INFO: readRocDacs: data/mj108/dacParameters35_C0.dat .. data/mj108/dacParameters35_C15.dat
[17:11:25.509] INFO: readTbmDacs: data/mj108/tbmParameters_C0a.dat .. data/mj108/tbmParameters_C0b.dat
[17:11:25.509] INFO: readMaskFile: data/mj108/defaultMaskFile.dat
[17:11:25.509] INFO: readTrimFile: data/mj108/trimParameters35_C0.dat .. data/mj108/trimParameters35_C15.dat
[17:11:25.659] INFO: clk: 4
[17:11:25.659] INFO: ctr: 4
[17:11:25.659] INFO: sda: 19
[17:11:25.659] INFO: tin: 9
[17:11:25.659] INFO: level: 15
[17:11:25.659] INFO: triggerdelay: 0
[17:11:25.659] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:11:25.659] INFO: Log level: DEBUG
[17:11:25.670] QUIET: Connection to board DTB_WRPRHI opened.
[17:11:25.673] 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:
------------------------------------------------------
[17:11:25.676] INFO: RPC call hashes of host and DTB match: 398089610
[17:11:27.275] INFO: DUT info:
[17:11:27.275] INFO: The DUT currently contains the following objects:
[17:11:27.275] INFO: 2 TBM Cores tbm08c (2 ON)
[17:11:27.275] INFO: TBM Core alpha (0): 7 registers set
[17:11:27.275] INFO: TBM Core beta (1): 7 registers set
[17:11:27.275] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:11:27.275] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.275] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.275] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.275] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.275] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.275] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.275] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.276] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.276] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.276] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.276] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.276] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.276] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.276] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.276] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.276] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:11:27.276] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:11:27.277] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:11:27.278] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:11:27.280] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31379456
[17:11:27.280] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2a30be0
[17:11:27.280] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x27d2e60
[17:11:27.280] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f5fa5d94010
[17:11:27.280] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f5fabfff510
[17:11:27.281] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31387648 fPxarMemory = 0x7f5fa5d94010
[17:11:27.282] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 390.7mA
[17:11:27.283] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[17:11:27.283] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[17:11:27.772] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2e1b800
[17:11:27.835] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:11:27.835] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:11:27.835] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:11:27.841] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:11:27.841] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:11:27.841] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:11:27.851] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:11:27.851] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:11:27.866] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:11:27.866] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:11:27.880] INFO: PixTestCmd::init()
[17:11:27.886] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:11:27.886] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:11:27.886] INFO: readGainPedestalParameters data/mj108/phCalibrationFitErr35_C0.dat .. data/mj108/phCalibrationFitErr35_C15.dat
[17:11:28.129] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:11:28.129] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:11:28.143] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:11:28.149] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:11:28.149] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:11:28.149] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:11:28.174] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:11:28.174] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:11:28.174] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:11:28.174] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:11:28.174] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:11:28.181] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:11:28.181] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:11:28.201] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:11:28.201] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:11:28.201] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:11:28.201] INFO: readReadbackCal: data/mj108/readbackCal_C0.dat .. data/mj108/readbackCal_C15.dat
[17:11:28.210] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:11:28.218] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:11:28.218] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:11:28.218] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:11:28.219] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:11:28.238] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:11:28.238] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:11:32.786] INFO: ######################################################################
[17:11:32.786] INFO: PixTestAlive::doTest()
[17:11:32.786] INFO: ######################################################################
[17:11:32.789] INFO: ----------------------------------------------------------------------
[17:11:32.790] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:11:32.790] INFO: ----------------------------------------------------------------------
[17:11:32.792] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:11:33.167] INFO: Expecting 41600 events.
[17:11:37.430] INFO: 41600 events read in total (3545ms).
[17:11:37.577] INFO: Test took 4785ms.
[17:11:37.587] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:11:37.587] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[17:11:37.587] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[17:11:37.856] INFO: PixTestAlive::aliveTest() done
[17:11:37.856] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 1
[17:11:37.856] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 47 36 67 84 74 78 79 93 81 73 102 94 104 68 39 42
[17:11:37.879] INFO: ----------------------------------------------------------------------
[17:11:37.879] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:11:37.879] INFO: ----------------------------------------------------------------------
[17:11:37.881] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:11:38.255] INFO: Expecting 41600 events.
[17:11:41.312] INFO: 41600 events read in total (2341ms).
[17:11:41.312] INFO: Test took 3431ms.
[17:11:41.312] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:11:41.312] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[17:11:41.312] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[17:11:41.313] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:11:41.735] INFO: PixTestAlive::maskTest() done
[17:11:41.735] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:11:41.753] INFO: ----------------------------------------------------------------------
[17:11:41.753] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:11:41.753] INFO: ----------------------------------------------------------------------
[17:11:41.756] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:11:42.129] INFO: Expecting 41600 events.
[17:11:46.377] INFO: 41600 events read in total (3532ms).
[17:11:46.378] INFO: Test took 4622ms.
[17:11:46.388] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:11:46.388] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[17:11:46.388] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[17:11:46.760] INFO: PixTestAlive::addressDecodingTest() done
[17:11:46.760] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:11:46.760] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[17:11:47.999] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[17:11:47.999] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[17:11:47.999] INFO: ----------------------------------------------------------------------
[17:11:47.999] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:11:47.999] INFO: ----------------------------------------------------------------------
[17:11:48.139] INFO: Expecting 768 events.
[17:11:49.271] INFO: 768 events read in total (417ms).
[17:11:49.271] INFO: Test took 1266ms.
[17:11:49.277] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:11:50.074] INFO: Expecting 41600 events.
[17:11:53.881] INFO: 41600 events read in total (3280ms).
[17:11:53.887] INFO: Test took 4610ms.
[17:11:54.057] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:11:54.057] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 968481
[17:11:54.057] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[17:11:54.057] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:11:54.128] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:11:54.793] INFO: Expecting 41600 events.
[17:11:58.668] INFO: 41600 events read in total (3349ms).
[17:11:58.674] INFO: Test took 4546ms.
[17:11:58.893] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:11:58.893] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 973525
[17:11:58.893] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[17:11:58.893] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:11:58.964] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:11:59.629] INFO: Expecting 41600 events.
[17:12:03.522] INFO: 41600 events read in total (3367ms).
[17:12:03.528] INFO: Test took 4564ms.
[17:12:03.688] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:03.688] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 975941
[17:12:03.688] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[17:12:03.688] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:12:03.758] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:12:04.423] INFO: Expecting 41600 events.
[17:12:08.322] INFO: 41600 events read in total (3372ms).
[17:12:08.328] INFO: Test took 4570ms.
[17:12:08.504] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:08.504] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 977950
[17:12:08.504] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[17:12:08.505] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:12:08.575] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:12:09.240] INFO: Expecting 41600 events.
[17:12:13.137] INFO: 41600 events read in total (3370ms).
[17:12:13.143] INFO: Test took 4568ms.
[17:12:13.327] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:13.327] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 977598
[17:12:13.327] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[17:12:13.327] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:12:13.399] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:12:14.063] INFO: Expecting 41600 events.
[17:12:17.977] INFO: 41600 events read in total (3387ms).
[17:12:17.983] INFO: Test took 4584ms.
[17:12:18.143] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:18.143] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 976790
[17:12:18.143] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[17:12:18.143] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:12:18.214] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:12:18.879] INFO: Expecting 41600 events.
[17:12:22.798] INFO: 41600 events read in total (3392ms).
[17:12:22.804] INFO: Test took 4590ms.
[17:12:22.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:22.964] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 975943
[17:12:22.964] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[17:12:22.964] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:12:23.035] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:12:23.700] INFO: Expecting 41600 events.
[17:12:27.626] INFO: 41600 events read in total (3399ms).
[17:12:27.631] INFO: Test took 4596ms.
[17:12:27.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:27.793] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 977233
[17:12:27.793] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[17:12:27.793] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:12:27.865] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:12:28.530] INFO: Expecting 41600 events.
[17:12:32.450] INFO: 41600 events read in total (3393ms).
[17:12:32.456] INFO: Test took 4591ms.
[17:12:32.635] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:32.635] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 977501
[17:12:32.635] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[17:12:32.636] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:12:32.707] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:12:33.372] INFO: Expecting 41600 events.
[17:12:37.286] INFO: 41600 events read in total (3387ms).
[17:12:37.293] INFO: Test took 4586ms.
[17:12:37.476] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:37.476] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 978183
[17:12:37.476] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[17:12:37.476] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:12:37.548] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:12:38.213] INFO: Expecting 41600 events.
[17:12:42.137] INFO: 41600 events read in total (3397ms).
[17:12:42.143] INFO: Test took 4595ms.
[17:12:42.327] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:42.327] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 976920
[17:12:42.327] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[17:12:42.328] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:12:42.398] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:12:43.063] INFO: Expecting 41600 events.
[17:12:46.979] INFO: 41600 events read in total (3390ms).
[17:12:46.985] INFO: Test took 4587ms.
[17:12:47.146] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:47.146] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 977066
[17:12:47.146] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[17:12:47.147] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:12:47.218] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:12:47.883] INFO: Expecting 41600 events.
[17:12:51.814] INFO: 41600 events read in total (3404ms).
[17:12:51.820] INFO: Test took 4602ms.
[17:12:51.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:51.979] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 978323
[17:12:51.979] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[17:12:51.979] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:12:52.050] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:12:52.714] INFO: Expecting 41600 events.
[17:12:56.631] INFO: 41600 events read in total (3390ms).
[17:12:56.636] INFO: Test took 4586ms.
[17:12:56.841] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:12:56.841] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 978458
[17:12:56.841] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[17:12:56.841] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:12:56.911] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:12:57.577] INFO: Expecting 41600 events.
[17:13:01.501] INFO: 41600 events read in total (3397ms).
[17:13:01.507] INFO: Test took 4595ms.
[17:13:01.667] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:01.667] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 977403
[17:13:01.667] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[17:13:01.668] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:01.739] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:02.404] INFO: Expecting 41600 events.
[17:13:06.325] INFO: 41600 events read in total (3394ms).
[17:13:06.331] INFO: Test took 4592ms.
[17:13:06.494] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:06.494] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 978481
[17:13:06.494] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[17:13:06.494] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:06.567] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:07.233] INFO: Expecting 41600 events.
[17:13:11.151] INFO: 41600 events read in total (3392ms).
[17:13:11.157] INFO: Test took 4590ms.
[17:13:11.344] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:11.344] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 977944
[17:13:11.344] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[17:13:11.344] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:11.415] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:12.081] INFO: Expecting 41600 events.
[17:13:16.012] INFO: 41600 events read in total (3404ms).
[17:13:16.018] INFO: Test took 4603ms.
[17:13:16.181] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:16.181] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 975602
[17:13:16.181] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[17:13:16.181] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:16.252] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:16.918] INFO: Expecting 41600 events.
[17:13:20.785] INFO: 41600 events read in total (3340ms).
[17:13:20.791] INFO: Test took 4539ms.
[17:13:20.954] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:20.954] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 975584
[17:13:20.954] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[17:13:20.954] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:21.025] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:21.691] INFO: Expecting 41600 events.
[17:13:25.389] INFO: 41600 events read in total (3171ms).
[17:13:25.395] INFO: Test took 4369ms.
[17:13:25.550] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:13:25.550] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 966301
[17:13:25.550] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[17:13:25.550] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:13:25.844] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 175 eff = 0.998726
[17:13:25.844] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 175 eff = 0.998822
[17:13:25.844] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 170 eff = 0.997188
[17:13:25.844] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 169 eff = 0.995096
[17:13:25.844] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 176 eff = 0.994663
[17:13:25.844] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 181 eff = 0.994231
[17:13:25.844] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 161 eff = 0.994567
[17:13:25.845] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 171 eff = 0.995673
[17:13:25.845] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 187 eff = 0.995313
[17:13:25.845] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 166 eff = 0.994303
[17:13:25.845] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 163 eff = 0.994231
[17:13:25.845] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 170 eff = 0.994351
[17:13:25.846] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 161 eff = 0.994135
[17:13:25.846] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 155 eff = 0.997139
[17:13:25.846] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 143 eff = 0.999087
[17:13:25.846] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 178 eff = 0.998365
[17:13:47.743] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[17:13:47.743] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[17:13:47.743] INFO: ----------------------------------------------------------------------
[17:13:47.743] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:13:47.743] INFO: ----------------------------------------------------------------------
[17:13:47.743] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[17:13:47.743] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[17:13:47.743] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[17:13:47.743] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[17:13:47.743] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[17:13:47.743] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[17:13:47.743] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[17:13:47.743] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[17:13:47.743] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[17:13:47.743] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[17:13:47.743] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[17:13:47.743] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[17:13:47.760] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:13:48.425] INFO: Expecting 208000 events.
[17:14:05.434] INFO: 208000 events read in total (16482ms).
[17:14:05.457] INFO: Test took 17697ms.
[17:14:06.382] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:14:06.382] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4570740
[17:14:06.382] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[17:14:06.382] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:14:06.910] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 1
[17:14:06.910] INFO: number of red-efficiency pixels: 302 207 482 779 947 1064 894 810 813 1011 958 933 964 476 234 250
[17:14:06.910] INFO: number of X-ray hits detected: 188233 135883 226745 333067 351726 351196 342816 312159 329255 367650 362518 336034 348939 224038 129412 164511
[17:14:06.910] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:14:06.910] INFO: number of Vcal hits detected: 207668 207779 207430 207065 206862 206687 206957 207062 207057 206780 206848 206869 206696 207460 207754 207692
[17:14:06.910] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.6 99.5 99.4 99.5 99.6 99.6 99.5 99.5 99.5 99.4 99.8 99.9 99.9
[17:14:06.910] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.5 99.4 99.5 99.5 99.5 99.4 99.4 99.5 99.4 99.7 99.9 99.9
[17:14:06.910] INFO: X-ray hit rate [MHz/cm2]: 55.2 39.8 66.5 97.6 103.1 102.9 100.5 91.5 96.5 107.8 106.3 98.5 102.3 65.7 37.9 48.2
[17:14:06.910] INFO: PixTestHighRate::doXPixelAlive() done
[17:14:06.955] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[17:14:06.955] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[17:14:06.955] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[17:14:06.955] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[17:14:06.955] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[17:14:06.955] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[17:14:06.955] INFO: PixTest:: pg_setup set to default.
[17:15:38.815] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:15:38.815] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 393.9mA
[17:15:38.816] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[17:15:38.816] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[17:15:38.816] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:15:38.816] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:15:38.816] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:15:38.817] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:15:38.817] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:15:38.817] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:15:38.817] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:15:38.817] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:15:38.817] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:15:38.817] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:15:38.896] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:15:38.896] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:15:38.905] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:15:38.906] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:15:38.906] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:15:38.906] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:15:38.906] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:15:38.906] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:15:38.908] QUIET: Connection to board 58 closed.
[17:15:38.987] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries