[10:08:31.202] INFO: *** Welcome to pxar ***
[10:08:31.202] INFO: *** Today: 2016/08/01
[10:08:31.225] INFO: *** Version: v1.9.0-816-gf0c0
[10:08:31.225] INFO: readRocDacs: data/mp521/dacParameters35_C0.dat .. data/mp521/dacParameters35_C15.dat
[10:08:31.226] INFO: readTbmDacs: data/mp521/tbmParameters_C0a.dat .. data/mp521/tbmParameters_C0b.dat
[10:08:31.226] INFO: readMaskFile: data/mp521/defaultMaskFile.dat
[10:08:31.226] INFO: readTrimFile: data/mp521/trimParameters35_C0.dat .. data/mp521/trimParameters35_C15.dat
[10:08:31.375] INFO: clk: 4
[10:08:31.375] INFO: ctr: 4
[10:08:31.375] INFO: sda: 19
[10:08:31.375] INFO: tin: 9
[10:08:31.375] INFO: level: 15
[10:08:31.375] INFO: triggerdelay: 0
[10:08:31.375] QUIET: Instanciating API for pxar v1.9.0+816~gf0c098f
[10:08:31.375] INFO: Log level: DEBUG
[10:08:31.388] QUIET: Connection to board DTB_WRPRHI opened.
[10:08:31.391] 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:
------------------------------------------------------
[10:08:31.394] INFO: RPC call hashes of host and DTB match: 398089610
[10:08:32.993] INFO: DUT info:
[10:08:32.993] INFO: The DUT currently contains the following objects:
[10:08:32.993] INFO: 2 TBM Cores tbm08c (2 ON)
[10:08:32.993] INFO: TBM Core alpha (0): 7 registers set
[10:08:32.993] INFO: TBM Core beta (1): 7 registers set
[10:08:32.993] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:08:32.993] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.993] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.993] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.993] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.993] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.993] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.993] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.993] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.993] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.993] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.993] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.993] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.993] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.993] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.993] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.993] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:08:32.994] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[10:08:32.995] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:08:32.996] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:08:32.998] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33439744
[10:08:32.998] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xd810e0
[10:08:32.998] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xb23370
[10:08:32.998] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f5145d94010
[10:08:32.998] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f514bfff510
[10:08:32.998] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33447936 fPxarMemory = 0x7f5145d94010
[10:08:33.000] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 388.3mA
[10:08:33.001] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 478.3mA
[10:08:33.001] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[10:08:33.489] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1170030
[10:08:33.618] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[10:08:33.618] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[10:08:33.618] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[10:08:33.639] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[10:08:33.639] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[10:08:33.640] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[10:08:33.691] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[10:08:33.691] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[10:08:33.700] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[10:08:33.700] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[10:08:33.735] INFO: PixTestCmd::init()
[10:08:33.746] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[10:08:33.746] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[10:08:33.746] INFO: readGainPedestalParameters data/mp521/phCalibrationFitErr35_C0.dat .. data/mp521/phCalibrationFitErr35_C15.dat
[10:08:33.994] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[10:08:33.994] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[10:08:34.006] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[10:08:34.012] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[10:08:34.012] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[10:08:34.012] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[10:08:34.031] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[10:08:34.031] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[10:08:34.031] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[10:08:34.031] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[10:08:34.031] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[10:08:34.038] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[10:08:34.038] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[10:08:34.055] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[10:08:34.055] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[10:08:34.055] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[10:08:34.055] INFO: readReadbackCal: data/mp521/readbackCal_C0.dat .. data/mp521/readbackCal_C15.dat
[10:08:34.069] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[10:08:34.076] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[10:08:34.076] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[10:08:34.076] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[10:08:34.076] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[10:08:34.090] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[10:08:34.090] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[10:08:37.943] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[10:08:40.652] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[10:08:40.652] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[10:08:40.652] INFO: ----------------------------------------------------------------------
[10:08:40.652] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[10:08:40.652] INFO: ----------------------------------------------------------------------
[10:08:41.629] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[10:08:54.527] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[10:08:54.531] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:09:19.004] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1270796 events.
[10:09:24.455] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1270796, pixels seen in all events: 5015483
[10:09:24.547] INFO: Resuming triggers.
[10:09:37.436] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[10:09:37.463] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:10:01.907] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1269722 events.
[10:10:07.303] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1269722, pixels seen in all events: 5014862
[10:10:07.526] INFO: Resuming triggers.
[10:10:20.419] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[10:10:20.448] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:10:44.735] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1270175 events.
[10:10:50.133] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1270175, pixels seen in all events: 5009332
[10:10:50.356] INFO: Resuming triggers.
[10:11:03.246] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[10:11:03.279] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:11:27.558] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1269905 events.
[10:11:33.022] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1269905, pixels seen in all events: 5012513
[10:11:33.177] INFO: Resuming triggers.
[10:11:41.684] INFO: data taking finished, elapsed time: 60 seconds.
[10:11:41.880] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:11:57.945] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 838089 events.
[10:12:01.507] DEBUG: <PixTestXray.cc/processData:L823> # events read: 838089, pixels seen in all events: 3306679
[10:12:01.574] INFO: PixTest:: pg_setup set to default.
[10:12:01.650] INFO: PixTestXray::doPhRun() done
[10:12:14.780] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:12:14.781] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 391.5mA
[10:12:14.782] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 479.1mA
[10:12:14.782] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[10:12:14.782] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:12:14.782] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:12:14.782] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:12:14.782] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:12:14.782] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:12:14.782] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:12:14.782] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:12:14.782] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:12:14.782] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:12:14.782] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:12:14.783] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:12:14.783] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:12:14.783] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:12:14.783] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:12:14.783] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:12:14.783] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:12:14.783] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:12:14.783] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:12:15.054] QUIET: Connection to board 58 closed.
[10:12:15.134] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[10:12:45.382] INFO: *** Welcome to pxar ***
[10:12:45.382] INFO: *** Today: 2016/08/01
[10:12:45.405] INFO: *** Version: v1.9.0-816-gf0c0
[10:12:45.405] INFO: readRocDacs: data/mp521/dacParameters35_C0.dat .. data/mp521/dacParameters35_C15.dat
[10:12:45.406] INFO: readTbmDacs: data/mp521/tbmParameters_C0a.dat .. data/mp521/tbmParameters_C0b.dat
[10:12:45.406] INFO: readMaskFile: data/mp521/defaultMaskFile.dat
[10:12:45.406] INFO: readTrimFile: data/mp521/trimParameters35_C0.dat .. data/mp521/trimParameters35_C15.dat
[10:12:45.554] INFO: clk: 4
[10:12:45.554] INFO: ctr: 4
[10:12:45.554] INFO: sda: 19
[10:12:45.554] INFO: tin: 9
[10:12:45.554] INFO: level: 15
[10:12:45.554] INFO: triggerdelay: 0
[10:12:45.554] QUIET: Instanciating API for pxar v1.9.0+816~gf0c098f
[10:12:45.554] INFO: Log level: DEBUG
[10:12:45.566] QUIET: Connection to board DTB_WRPRHI opened.
[10:12:45.570] 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:
------------------------------------------------------
[10:12:45.572] INFO: RPC call hashes of host and DTB match: 398089610
[10:12:47.172] INFO: DUT info:
[10:12:47.172] INFO: The DUT currently contains the following objects:
[10:12:47.172] INFO: 2 TBM Cores tbm08c (2 ON)
[10:12:47.172] INFO: TBM Core alpha (0): 7 registers set
[10:12:47.172] INFO: TBM Core beta (1): 7 registers set
[10:12:47.172] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:12:47.172] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.172] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.172] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.172] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.172] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.172] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.172] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.172] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.172] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.172] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.172] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.172] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.172] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.173] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.173] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.173] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[10:12:47.173] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[10:12:47.174] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:12:47.175] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:12:47.177] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 36896768
[10:12:47.177] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x22590e0
[10:12:47.177] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1ffb370
[10:12:47.177] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fe8ed393010
[10:12:47.177] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fe8f35fe510
[10:12:47.177] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 36904960 fPxarMemory = 0x7fe8ed393010
[10:12:47.179] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 388.3mA
[10:12:47.180] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 478.3mA
[10:12:47.180] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[10:12:47.669] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2647fd0
[10:12:47.792] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[10:12:47.792] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[10:12:47.793] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[10:12:47.798] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[10:12:47.798] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[10:12:47.798] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[10:12:47.820] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[10:12:47.820] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[10:12:47.830] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[10:12:47.830] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[10:12:47.846] INFO: PixTestCmd::init()
[10:12:47.878] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[10:12:47.878] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[10:12:47.878] INFO: readGainPedestalParameters data/mp521/phCalibrationFitErr35_C0.dat .. data/mp521/phCalibrationFitErr35_C15.dat
[10:12:48.125] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[10:12:48.125] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[10:12:48.138] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[10:12:48.143] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[10:12:48.144] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[10:12:48.144] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[10:12:48.163] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[10:12:48.163] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[10:12:48.163] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[10:12:48.163] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[10:12:48.163] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[10:12:48.168] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[10:12:48.169] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[10:12:48.182] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[10:12:48.182] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[10:12:48.182] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[10:12:48.182] INFO: readReadbackCal: data/mp521/readbackCal_C0.dat .. data/mp521/readbackCal_C15.dat
[10:12:48.192] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[10:12:48.199] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[10:12:48.199] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[10:12:48.199] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[10:12:48.199] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[10:12:48.211] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[10:12:48.211] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[10:12:56.615] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[10:12:58.604] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[10:12:58.604] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[10:12:58.604] INFO: ----------------------------------------------------------------------
[10:12:58.604] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[10:12:58.604] INFO: ----------------------------------------------------------------------
[10:12:59.576] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[10:13:07.454] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[10:13:07.458] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:13:30.197] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 776234 events.
[10:13:42.000] DEBUG: <PixTestXray.cc/processData:L823> # events read: 776234, pixels seen in all events: 10937976
[10:13:42.251] INFO: Resuming triggers.
[10:13:50.143] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[10:13:50.171] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:14:12.844] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 777460 events.
[10:14:24.669] DEBUG: <PixTestXray.cc/processData:L823> # events read: 777460, pixels seen in all events: 10922745
[10:14:24.832] INFO: Resuming triggers.
[10:14:32.725] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:14:32.751] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:14:55.509] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 777547 events.
[10:15:07.326] DEBUG: <PixTestXray.cc/processData:L823> # events read: 777547, pixels seen in all events: 10921799
[10:15:07.451] INFO: Resuming triggers.
[10:15:15.345] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[10:15:15.412] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:15:38.175] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 777684 events.
[10:15:50.014] DEBUG: <PixTestXray.cc/processData:L823> # events read: 777684, pixels seen in all events: 10919366
[10:15:50.178] INFO: Resuming triggers.
[10:15:58.072] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[10:15:58.101] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:16:20.756] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 777734 events.
[10:16:32.589] DEBUG: <PixTestXray.cc/processData:L823> # events read: 777734, pixels seen in all events: 10919318
[10:16:32.751] INFO: Resuming triggers.
[10:16:40.645] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[10:16:40.673] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:17:03.365] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 777747 events.
[10:17:15.218] DEBUG: <PixTestXray.cc/processData:L823> # events read: 777747, pixels seen in all events: 10919035
[10:17:15.382] INFO: Resuming triggers.
[10:17:23.280] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[10:17:23.309] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:17:45.961] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 778027 events.
[10:17:57.754] DEBUG: <PixTestXray.cc/processData:L823> # events read: 778027, pixels seen in all events: 10916063
[10:17:57.876] INFO: Resuming triggers.
[10:18:02.713] INFO: data taking finished, elapsed time: 60 seconds.
[10:18:02.909] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:18:16.815] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 476494 events.
[10:18:24.049] DEBUG: <PixTestXray.cc/processData:L823> # events read: 476494, pixels seen in all events: 6685712
[10:18:24.159] INFO: PixTest:: pg_setup set to default.
[10:18:24.195] INFO: PixTestXray::doPhRun() done
[10:18:48.604] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:18:48.605] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 391.5mA
[10:18:48.606] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 479.1mA
[10:18:48.606] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[10:18:48.606] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:18:48.606] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:18:48.606] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:18:48.606] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:18:48.606] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:18:48.606] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:18:48.607] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:18:48.607] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:18:48.607] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:18:48.607] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:18:48.607] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:18:48.607] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:18:48.607] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:18:48.607] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:18:48.607] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:18:48.607] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:18:48.607] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:18:48.607] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:18:48.916] QUIET: Connection to board 58 closed.
[10:18:48.996] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[10:02:18.103] INFO: *** Welcome to pxar ***
[10:02:18.103] INFO: *** Today: 2016/08/01
[10:02:18.125] INFO: *** Version: v1.9.0-816-gf0c0
[10:02:18.125] INFO: readRocDacs: data/mp521/dacParameters35_C0.dat .. data/mp521/dacParameters35_C15.dat
[10:02:18.126] INFO: readTbmDacs: data/mp521/tbmParameters_C0a.dat .. data/mp521/tbmParameters_C0b.dat
[10:02:18.126] INFO: readMaskFile: data/mp521/defaultMaskFile.dat
[10:02:18.126] INFO: readTrimFile: data/mp521/trimParameters35_C0.dat .. data/mp521/trimParameters35_C15.dat
[10:02:18.276] INFO: clk: 4
[10:02:18.276] INFO: ctr: 4
[10:02:18.276] INFO: sda: 19
[10:02:18.276] INFO: tin: 9
[10:02:18.276] INFO: level: 15
[10:02:18.276] INFO: triggerdelay: 0
[10:02:18.276] QUIET: Instanciating API for pxar v1.9.0+816~gf0c098f
[10:02:18.276] INFO: Log level: DEBUG
[10:02:18.287] QUIET: Connection to board DTB_WRPRHI opened.
[10:02:18.290] 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:
------------------------------------------------------
[10:02:18.293] INFO: RPC call hashes of host and DTB match: 398089610
[10:02:19.892] INFO: DUT info:
[10:02:19.892] INFO: The DUT currently contains the following objects:
[10:02:19.892] INFO: 2 TBM Cores tbm08c (2 ON)
[10:02:19.892] INFO: TBM Core alpha (0): 7 registers set
[10:02:19.892] INFO: TBM Core beta (1): 7 registers set
[10:02:19.892] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:02:19.892] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.892] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.892] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.892] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.892] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.892] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.892] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.892] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.892] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.892] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.892] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.892] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.892] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.892] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.892] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.892] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:02:19.893] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[10:02:19.894] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:02:19.895] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:02:19.897] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33579008
[10:02:19.897] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x16f00e0
[10:02:19.897] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1492370
[10:02:19.897] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f0a3dd94010
[10:02:19.897] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f0a43fff510
[10:02:19.897] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33587200 fPxarMemory = 0x7f0a3dd94010
[10:02:19.899] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 388.3mA
[10:02:19.900] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 478.3mA
[10:02:19.900] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[10:02:20.388] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1adf090
[10:02:20.508] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[10:02:20.508] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[10:02:20.508] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[10:02:20.514] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[10:02:20.514] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[10:02:20.514] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[10:02:20.534] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[10:02:20.534] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[10:02:20.546] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[10:02:20.546] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[10:02:20.565] INFO: PixTestCmd::init()
[10:02:20.597] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[10:02:20.597] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[10:02:20.597] INFO: readGainPedestalParameters data/mp521/phCalibrationFitErr35_C0.dat .. data/mp521/phCalibrationFitErr35_C15.dat
[10:02:20.847] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[10:02:20.847] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[10:02:20.858] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[10:02:20.865] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[10:02:20.865] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[10:02:20.865] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[10:02:20.884] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[10:02:20.884] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[10:02:20.884] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[10:02:20.884] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[10:02:20.884] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[10:02:20.889] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[10:02:20.889] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[10:02:20.902] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[10:02:20.902] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[10:02:20.902] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[10:02:20.902] INFO: readReadbackCal: data/mp521/readbackCal_C0.dat .. data/mp521/readbackCal_C15.dat
[10:02:20.914] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[10:02:20.922] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[10:02:20.922] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[10:02:20.922] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[10:02:20.922] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[10:02:20.933] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[10:02:20.934] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[10:02:29.479] INFO: ######################################################################
[10:02:29.479] INFO: PixTestAlive::doTest()
[10:02:29.479] INFO: ######################################################################
[10:02:29.483] INFO: ----------------------------------------------------------------------
[10:02:29.483] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:02:29.483] INFO: ----------------------------------------------------------------------
[10:02:29.485] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:02:29.861] INFO: Expecting 41600 events.
[10:02:34.121] INFO: 41600 events read in total (3542ms).
[10:02:34.269] INFO: Test took 4784ms.
[10:02:34.279] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:34.279] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[10:02:34.279] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[10:02:34.547] INFO: PixTestAlive::aliveTest() done
[10:02:34.547] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:02:34.547] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 17 11 16 31 52 33 46 25 36 34 33 39 45 22 16 9
[10:02:34.569] INFO: ----------------------------------------------------------------------
[10:02:34.569] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:02:34.569] INFO: ----------------------------------------------------------------------
[10:02:34.572] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:02:34.946] INFO: Expecting 41600 events.
[10:02:37.996] INFO: 41600 events read in total (2335ms).
[10:02:37.996] INFO: Test took 3424ms.
[10:02:37.996] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:37.996] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[10:02:37.996] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[10:02:37.997] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:02:38.419] INFO: PixTestAlive::maskTest() done
[10:02:38.419] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:02:38.438] INFO: ----------------------------------------------------------------------
[10:02:38.438] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:02:38.438] INFO: ----------------------------------------------------------------------
[10:02:38.441] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:02:38.814] INFO: Expecting 41600 events.
[10:02:43.064] INFO: 41600 events read in total (3534ms).
[10:02:43.067] INFO: Test took 4626ms.
[10:02:43.077] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:43.077] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[10:02:43.077] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[10:02:43.453] INFO: PixTestAlive::addressDecodingTest() done
[10:02:43.453] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:02:43.453] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[10:02:50.772] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[10:02:50.772] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C3_V0 -> PixelAlive_mod
[10:02:53.828] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[10:02:53.828] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[10:02:53.828] INFO: ----------------------------------------------------------------------
[10:02:53.828] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:02:53.828] INFO: ----------------------------------------------------------------------
[10:02:53.967] INFO: Expecting 768 events.
[10:02:55.100] INFO: 768 events read in total (417ms).
[10:02:55.100] INFO: Test took 1266ms.
[10:02:55.106] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:02:55.904] INFO: Expecting 41600 events.
[10:02:59.094] INFO: 41600 events read in total (2663ms).
[10:02:59.098] INFO: Test took 3992ms.
[10:02:59.178] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:59.178] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 456832
[10:02:59.178] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[10:02:59.179] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:02:59.217] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:02:59.882] INFO: Expecting 41600 events.
[10:03:03.180] INFO: 41600 events read in total (2771ms).
[10:03:03.183] INFO: Test took 3966ms.
[10:03:03.260] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:03.260] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464729
[10:03:03.260] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[10:03:03.260] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:03:03.300] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:03:03.965] INFO: Expecting 41600 events.
[10:03:07.286] INFO: 41600 events read in total (2794ms).
[10:03:07.289] INFO: Test took 3989ms.
[10:03:07.366] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:07.366] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465210
[10:03:07.366] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[10:03:07.367] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:03:07.407] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:03:08.072] INFO: Expecting 41600 events.
[10:03:11.394] INFO: 41600 events read in total (2795ms).
[10:03:11.397] INFO: Test took 3990ms.
[10:03:11.474] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:11.474] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 466722
[10:03:11.474] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[10:03:11.475] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:03:11.515] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:03:12.180] INFO: Expecting 41600 events.
[10:03:15.514] INFO: 41600 events read in total (2808ms).
[10:03:15.517] INFO: Test took 4002ms.
[10:03:15.594] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:15.594] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 466681
[10:03:15.594] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[10:03:15.594] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:03:15.634] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:03:16.299] INFO: Expecting 41600 events.
[10:03:19.643] INFO: 41600 events read in total (2817ms).
[10:03:19.646] INFO: Test took 4012ms.
[10:03:19.725] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:19.725] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 466747
[10:03:19.725] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[10:03:19.725] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:03:19.766] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:03:20.431] INFO: Expecting 41600 events.
[10:03:23.776] INFO: 41600 events read in total (2818ms).
[10:03:23.780] INFO: Test took 4014ms.
[10:03:23.856] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:23.856] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465669
[10:03:23.856] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[10:03:23.857] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:03:23.897] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:03:24.563] INFO: Expecting 41600 events.
[10:03:27.907] INFO: 41600 events read in total (2817ms).
[10:03:27.910] INFO: Test took 4012ms.
[10:03:27.986] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:27.986] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465406
[10:03:27.986] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[10:03:27.986] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:03:28.026] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:03:28.691] INFO: Expecting 41600 events.
[10:03:32.035] INFO: 41600 events read in total (2817ms).
[10:03:32.039] INFO: Test took 4013ms.
[10:03:32.116] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:32.116] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 466647
[10:03:32.116] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[10:03:32.116] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:03:32.156] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:03:32.821] INFO: Expecting 41600 events.
[10:03:36.164] INFO: 41600 events read in total (2816ms).
[10:03:36.168] INFO: Test took 4012ms.
[10:03:36.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:36.244] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465617
[10:03:36.244] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[10:03:36.245] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:03:36.285] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:03:36.950] INFO: Expecting 41600 events.
[10:03:40.293] INFO: 41600 events read in total (2817ms).
[10:03:40.297] INFO: Test took 4012ms.
[10:03:40.374] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:40.375] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 466472
[10:03:40.375] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[10:03:40.375] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:03:40.415] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:03:41.080] INFO: Expecting 41600 events.
[10:03:44.426] INFO: 41600 events read in total (2819ms).
[10:03:44.429] INFO: Test took 4014ms.
[10:03:44.506] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:44.506] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 466376
[10:03:44.507] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[10:03:44.507] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:03:44.547] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:03:45.212] INFO: Expecting 41600 events.
[10:03:48.555] INFO: 41600 events read in total (2816ms).
[10:03:48.558] INFO: Test took 4011ms.
[10:03:48.635] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:48.635] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 466184
[10:03:48.635] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[10:03:48.636] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:03:48.677] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:03:49.341] INFO: Expecting 41600 events.
[10:03:52.676] INFO: 41600 events read in total (2808ms).
[10:03:52.679] INFO: Test took 4002ms.
[10:03:52.757] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:52.757] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 468103
[10:03:52.757] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[10:03:52.758] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:03:52.798] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:03:53.464] INFO: Expecting 41600 events.
[10:03:56.798] INFO: 41600 events read in total (2807ms).
[10:03:56.802] INFO: Test took 4003ms.
[10:03:56.879] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:56.879] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465496
[10:03:56.879] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[10:03:56.879] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:03:56.919] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:03:57.585] INFO: Expecting 41600 events.
[10:04:00.922] INFO: 41600 events read in total (2811ms).
[10:04:00.925] INFO: Test took 4005ms.
[10:04:01.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:01.002] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465781
[10:04:01.002] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[10:04:01.002] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:04:01.042] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:04:01.707] INFO: Expecting 41600 events.
[10:04:05.049] INFO: 41600 events read in total (2815ms).
[10:04:05.053] INFO: Test took 4011ms.
[10:04:05.130] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:05.130] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465481
[10:04:05.130] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[10:04:05.130] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:04:05.171] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:04:05.836] INFO: Expecting 41600 events.
[10:04:09.180] INFO: 41600 events read in total (2817ms).
[10:04:09.183] INFO: Test took 4012ms.
[10:04:09.259] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:09.259] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465251
[10:04:09.259] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[10:04:09.260] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:04:09.300] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:04:09.965] INFO: Expecting 41600 events.
[10:04:13.294] INFO: 41600 events read in total (2802ms).
[10:04:13.297] INFO: Test took 3997ms.
[10:04:13.374] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:13.374] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464761
[10:04:13.374] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[10:04:13.374] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:04:13.415] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:04:14.080] INFO: Expecting 41600 events.
[10:04:17.259] INFO: 41600 events read in total (2653ms).
[10:04:17.263] INFO: Test took 3848ms.
[10:04:17.338] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:17.338] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 458306
[10:04:17.338] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[10:04:17.339] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:04:17.599] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 152 eff = 0.999543
[10:04:17.599] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 155 eff = 0.999784
[10:04:17.600] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 150 eff = 0.999471
[10:04:17.600] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 163 eff = 0.999207
[10:04:17.600] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 158 eff = 0.999255
[10:04:17.600] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 169 eff = 0.99875
[10:04:17.600] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 158 eff = 0.99899
[10:04:17.600] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 144 eff = 0.999183
[10:04:17.601] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 195 eff = 0.999063
[10:04:17.601] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 170 eff = 0.99899
[10:04:17.601] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 167 eff = 0.999087
[10:04:17.601] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 171 eff = 0.99899
[10:04:17.601] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 182 eff = 0.999159
[10:04:17.601] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 148 eff = 0.999471
[10:04:17.602] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 150 eff = 0.999784
[10:04:17.602] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 186 eff = 0.99988
[10:04:21.236] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[10:04:21.236] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[10:04:21.236] INFO: ----------------------------------------------------------------------
[10:04:21.236] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:04:21.236] INFO: ----------------------------------------------------------------------
[10:04:21.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[10:04:21.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[10:04:21.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[10:04:21.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[10:04:21.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[10:04:21.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[10:04:21.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[10:04:21.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[10:04:21.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[10:04:21.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[10:04:21.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[10:04:21.237] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[10:04:21.252] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:04:21.918] INFO: Expecting 208000 events.
[10:04:34.436] INFO: 208000 events read in total (11992ms).
[10:04:34.447] INFO: Test took 13195ms.
[10:04:34.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:34.793] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2048569
[10:04:34.793] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[10:04:34.793] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:04:35.161] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:04:35.161] INFO: number of red-efficiency pixels: 78 62 168 240 254 247 269 231 248 260 215 212 250 136 57 73
[10:04:35.161] INFO: number of X-ray hits detected: 61475 57305 98999 149411 158136 161745 160386 149448 164289 162089 155322 149681 149754 96963 58207 48800
[10:04:35.161] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:04:35.161] INFO: number of Vcal hits detected: 207873 207936 207827 207755 207744 207748 207723 207762 207738 207737 207748 207781 207739 207856 207941 207925
[10:04:35.161] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[10:04:35.161] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[10:04:35.161] INFO: X-ray hit rate [MHz/cm2]: 18.0 16.8 29.0 43.8 46.4 47.4 47.0 43.8 48.2 47.5 45.5 43.9 43.9 28.4 17.1 14.3
[10:04:35.161] INFO: PixTestHighRate::doXPixelAlive() done
[10:04:35.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[10:04:35.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[10:04:35.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[10:04:35.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[10:04:35.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[10:04:35.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[10:04:35.207] INFO: PixTest:: pg_setup set to default.
[10:04:41.196] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[10:04:41.196] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[10:04:42.932] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:04:42.933] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 391.5mA
[10:04:42.934] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 479.1mA
[10:04:42.934] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[10:04:42.934] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:04:42.934] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:04:42.934] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:04:42.934] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:04:42.934] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:04:42.934] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:04:42.934] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:04:42.934] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:04:42.934] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:04:42.934] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:04:43.020] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:04:43.020] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:04:43.033] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:04:43.033] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:04:43.034] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:04:43.034] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:04:43.034] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:04:43.034] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:04:43.036] QUIET: Connection to board 58 closed.
[10:04:43.115] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[09:58:47.332] INFO: *** Welcome to pxar ***
[09:58:47.332] INFO: *** Today: 2016/08/01
[09:58:47.354] INFO: *** Version: v1.9.0-816-gf0c0
[09:58:47.354] INFO: readRocDacs: data/mp521/dacParameters35_C0.dat .. data/mp521/dacParameters35_C15.dat
[09:58:47.355] INFO: readTbmDacs: data/mp521/tbmParameters_C0a.dat .. data/mp521/tbmParameters_C0b.dat
[09:58:47.355] INFO: readMaskFile: data/mp521/defaultMaskFile.dat
[09:58:47.355] INFO: readTrimFile: data/mp521/trimParameters35_C0.dat .. data/mp521/trimParameters35_C15.dat
[09:58:47.508] INFO: clk: 4
[09:58:47.508] INFO: ctr: 4
[09:58:47.508] INFO: sda: 19
[09:58:47.508] INFO: tin: 9
[09:58:47.508] INFO: level: 15
[09:58:47.508] INFO: triggerdelay: 0
[09:58:47.508] QUIET: Instanciating API for pxar v1.9.0+816~gf0c098f
[09:58:47.508] INFO: Log level: DEBUG
[09:58:47.519] QUIET: Connection to board DTB_WRPRHI opened.
[09:58:47.523] 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:
------------------------------------------------------
[09:58:47.526] INFO: RPC call hashes of host and DTB match: 398089610
[09:58:49.125] INFO: DUT info:
[09:58:49.125] INFO: The DUT currently contains the following objects:
[09:58:49.125] INFO: 2 TBM Cores tbm08c (2 ON)
[09:58:49.125] INFO: TBM Core alpha (0): 7 registers set
[09:58:49.125] INFO: TBM Core beta (1): 7 registers set
[09:58:49.125] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:58:49.125] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.125] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.125] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.125] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.125] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.125] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.125] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.125] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.125] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.125] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.125] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.125] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.125] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.126] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.126] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.126] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[09:58:49.126] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[09:58:49.127] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:58:49.128] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:58:49.130] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35082240
[09:58:49.130] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x164f0e0
[09:58:49.130] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x13f1370
[09:58:49.130] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f7bfdd94010
[09:58:49.130] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f7c03fff510
[09:58:49.130] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35090432 fPxarMemory = 0x7f7bfdd94010
[09:58:49.132] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 388.3mA
[09:58:49.133] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 477.5mA
[09:58:49.133] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[09:58:49.624] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1a3df80
[09:58:49.745] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[09:58:49.745] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[09:58:49.746] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[09:58:49.751] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[09:58:49.751] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[09:58:49.751] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[09:58:49.772] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[09:58:49.772] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[09:58:49.810] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[09:58:49.810] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[09:58:49.826] INFO: PixTestCmd::init()
[09:58:49.830] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[09:58:49.830] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[09:58:49.830] INFO: readGainPedestalParameters data/mp521/phCalibrationFitErr35_C0.dat .. data/mp521/phCalibrationFitErr35_C15.dat
[09:58:50.090] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[09:58:50.090] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[09:58:50.104] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[09:58:50.169] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[09:58:50.169] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[09:58:50.169] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[09:58:50.189] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[09:58:50.189] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[09:58:50.189] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[09:58:50.189] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[09:58:50.189] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[09:58:50.195] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[09:58:50.195] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[09:58:50.212] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[09:58:50.212] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[09:58:50.212] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[09:58:50.212] INFO: readReadbackCal: data/mp521/readbackCal_C0.dat .. data/mp521/readbackCal_C15.dat
[09:58:50.221] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[09:58:50.228] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[09:58:50.228] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[09:58:50.228] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[09:58:50.228] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[09:58:50.244] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[09:58:50.244] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[09:59:05.415] INFO: ######################################################################
[09:59:05.415] INFO: PixTestAlive::doTest()
[09:59:05.415] INFO: ######################################################################
[09:59:05.419] INFO: ----------------------------------------------------------------------
[09:59:05.419] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:59:05.419] INFO: ----------------------------------------------------------------------
[09:59:05.421] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:59:05.796] INFO: Expecting 41600 events.
[09:59:10.062] INFO: 41600 events read in total (3548ms).
[09:59:10.211] INFO: Test took 4790ms.
[09:59:10.222] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:10.222] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[09:59:10.222] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[09:59:10.492] INFO: PixTestAlive::aliveTest() done
[09:59:10.492] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:59:10.492] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 21 20 30 63 57 79 53 70 62 64 67 55 68 37 29 20
[09:59:10.515] INFO: ----------------------------------------------------------------------
[09:59:10.515] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:59:10.515] INFO: ----------------------------------------------------------------------
[09:59:10.518] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:59:10.891] INFO: Expecting 41600 events.
[09:59:13.937] INFO: 41600 events read in total (2330ms).
[09:59:13.937] INFO: Test took 3419ms.
[09:59:13.937] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:13.937] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[09:59:13.937] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[09:59:13.938] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:59:14.361] INFO: PixTestAlive::maskTest() done
[09:59:14.361] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:59:14.380] INFO: ----------------------------------------------------------------------
[09:59:14.380] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:59:14.380] INFO: ----------------------------------------------------------------------
[09:59:14.383] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:59:14.756] INFO: Expecting 41600 events.
[09:59:19.003] INFO: 41600 events read in total (3531ms).
[09:59:19.004] INFO: Test took 4621ms.
[09:59:19.015] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:19.015] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[09:59:19.015] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[09:59:19.388] INFO: PixTestAlive::addressDecodingTest() done
[09:59:19.388] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:59:19.388] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[09:59:25.004] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[09:59:25.004] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C4_V0 -> PixelAlive_mod
[09:59:34.332] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[09:59:34.332] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[09:59:34.332] INFO: ----------------------------------------------------------------------
[09:59:34.332] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[09:59:34.332] INFO: ----------------------------------------------------------------------
[09:59:34.471] INFO: Expecting 768 events.
[09:59:35.604] INFO: 768 events read in total (417ms).
[09:59:35.604] INFO: Test took 1266ms.
[09:59:35.610] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:59:36.408] INFO: Expecting 41600 events.
[09:59:39.896] INFO: 41600 events read in total (2962ms).
[09:59:39.901] INFO: Test took 4291ms.
[09:59:40.027] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:40.027] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 725613
[09:59:40.027] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[09:59:40.027] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:59:40.082] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:59:40.747] INFO: Expecting 41600 events.
[09:59:44.327] INFO: 41600 events read in total (3054ms).
[09:59:44.331] INFO: Test took 4249ms.
[09:59:44.454] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:44.454] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 733949
[09:59:44.454] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[09:59:44.454] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:59:44.510] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:59:45.174] INFO: Expecting 41600 events.
[09:59:48.782] INFO: 41600 events read in total (3081ms).
[09:59:48.786] INFO: Test took 4276ms.
[09:59:48.909] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:48.909] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 735308
[09:59:48.909] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[09:59:48.910] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:59:48.966] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:59:49.631] INFO: Expecting 41600 events.
[09:59:53.246] INFO: 41600 events read in total (3088ms).
[09:59:53.250] INFO: Test took 4284ms.
[09:59:53.373] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:53.373] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 736920
[09:59:53.373] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[09:59:53.374] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:59:53.430] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:59:54.095] INFO: Expecting 41600 events.
[09:59:57.712] INFO: 41600 events read in total (3090ms).
[09:59:57.716] INFO: Test took 4286ms.
[09:59:57.841] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:59:57.841] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 736967
[09:59:57.841] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[09:59:57.841] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:59:57.897] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:59:58.562] INFO: Expecting 41600 events.
[10:00:02.179] INFO: 41600 events read in total (3090ms).
[10:00:02.184] INFO: Test took 4287ms.
[10:00:02.306] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:02.306] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 735495
[10:00:02.306] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[10:00:02.307] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:00:02.363] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:00:03.027] INFO: Expecting 41600 events.
[10:00:06.650] INFO: 41600 events read in total (3096ms).
[10:00:06.654] INFO: Test took 4291ms.
[10:00:06.778] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:06.778] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 735283
[10:00:06.778] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[10:00:06.778] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:00:06.835] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:00:07.501] INFO: Expecting 41600 events.
[10:00:11.156] INFO: 41600 events read in total (3129ms).
[10:00:11.161] INFO: Test took 4326ms.
[10:00:11.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:11.283] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 736992
[10:00:11.283] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[10:00:11.283] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:00:11.340] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:00:12.005] INFO: Expecting 41600 events.
[10:00:15.638] INFO: 41600 events read in total (3107ms).
[10:00:15.642] INFO: Test took 4302ms.
[10:00:15.765] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:15.765] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 735079
[10:00:15.765] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[10:00:15.765] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:00:15.822] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:00:16.486] INFO: Expecting 41600 events.
[10:00:20.149] INFO: 41600 events read in total (3136ms).
[10:00:20.154] INFO: Test took 4332ms.
[10:00:20.275] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:20.275] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 737418
[10:00:20.275] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[10:00:20.276] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:00:20.332] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:00:20.997] INFO: Expecting 41600 events.
[10:00:24.656] INFO: 41600 events read in total (3133ms).
[10:00:24.660] INFO: Test took 4328ms.
[10:00:24.783] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:24.783] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 736903
[10:00:24.783] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[10:00:24.783] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:00:24.839] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:00:25.504] INFO: Expecting 41600 events.
[10:00:29.163] INFO: 41600 events read in total (3132ms).
[10:00:29.168] INFO: Test took 4329ms.
[10:00:29.292] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:29.292] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 735871
[10:00:29.293] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[10:00:29.293] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:00:29.349] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:00:30.014] INFO: Expecting 41600 events.
[10:00:33.679] INFO: 41600 events read in total (3139ms).
[10:00:33.684] INFO: Test took 4335ms.
[10:00:33.807] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:33.807] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 735151
[10:00:33.807] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[10:00:33.807] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:00:33.863] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:00:34.528] INFO: Expecting 41600 events.
[10:00:38.191] INFO: 41600 events read in total (3136ms).
[10:00:38.196] INFO: Test took 4333ms.
[10:00:38.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:38.319] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 735190
[10:00:38.319] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[10:00:38.319] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:00:38.375] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:00:39.040] INFO: Expecting 41600 events.
[10:00:42.699] INFO: 41600 events read in total (3132ms).
[10:00:42.703] INFO: Test took 4328ms.
[10:00:42.826] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:42.826] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 735355
[10:00:42.826] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[10:00:42.826] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:00:42.882] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:00:43.547] INFO: Expecting 41600 events.
[10:00:47.201] INFO: 41600 events read in total (3127ms).
[10:00:47.206] INFO: Test took 4324ms.
[10:00:47.330] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:47.330] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 736639
[10:00:47.330] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[10:00:47.330] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:00:47.387] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:00:48.052] INFO: Expecting 41600 events.
[10:00:51.713] INFO: 41600 events read in total (3134ms).
[10:00:51.718] INFO: Test took 4331ms.
[10:00:51.841] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:51.841] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 734829
[10:00:51.841] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[10:00:51.841] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:00:51.898] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:00:52.563] INFO: Expecting 41600 events.
[10:00:56.211] INFO: 41600 events read in total (3121ms).
[10:00:56.216] INFO: Test took 4318ms.
[10:00:56.338] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:00:56.338] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 736086
[10:00:56.338] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[10:00:56.339] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:00:56.395] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:00:57.060] INFO: Expecting 41600 events.
[10:01:00.689] INFO: 41600 events read in total (3102ms).
[10:01:00.694] INFO: Test took 4299ms.
[10:01:00.813] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:00.813] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 735009
[10:01:00.813] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[10:01:00.814] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:01:00.871] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:01:01.536] INFO: Expecting 41600 events.
[10:01:04.995] INFO: 41600 events read in total (2933ms).
[10:01:05.000] INFO: Test took 4129ms.
[10:01:05.119] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:05.119] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 727022
[10:01:05.119] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[10:01:05.120] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:01:05.397] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 140 eff = 0.999135
[10:01:05.397] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 152 eff = 0.999519
[10:01:05.397] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 171 eff = 0.998606
[10:01:05.398] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 171 eff = 0.997548
[10:01:05.398] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 164 eff = 0.996971
[10:01:05.398] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 171 eff = 0.997692
[10:01:05.398] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 164 eff = 0.997188
[10:01:05.399] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 171 eff = 0.998125
[10:01:05.399] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 200 eff = 0.997163
[10:01:05.399] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 173 eff = 0.997428
[10:01:05.399] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 167 eff = 0.997452
[10:01:05.400] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 180 eff = 0.997308
[10:01:05.400] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 179 eff = 0.997404
[10:01:05.400] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 157 eff = 0.99887
[10:01:05.400] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 131 eff = 0.999471
[10:01:05.401] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 196 eff = 0.999567
[10:01:15.356] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[10:01:15.356] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[10:01:15.356] INFO: ----------------------------------------------------------------------
[10:01:15.356] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:01:15.356] INFO: ----------------------------------------------------------------------
[10:01:15.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[10:01:15.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[10:01:15.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[10:01:15.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[10:01:15.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[10:01:15.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[10:01:15.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[10:01:15.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[10:01:15.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[10:01:15.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[10:01:15.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[10:01:15.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[10:01:15.373] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:01:16.038] INFO: Expecting 208000 events.
[10:01:30.633] INFO: 208000 events read in total (14068ms).
[10:01:30.650] INFO: Test took 15277ms.
[10:01:31.236] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:31.236] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3383293
[10:01:31.236] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[10:01:31.237] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:01:31.687] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:01:31.687] INFO: number of red-efficiency pixels: 143 139 283 503 546 609 588 437 512 584 539 525 525 310 189 111
[10:01:31.687] INFO: number of X-ray hits detected: 101730 96315 166577 250249 265328 270195 267507 248053 274755 271333 260180 250568 251546 163636 97022 81740
[10:01:31.687] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:01:31.687] INFO: number of Vcal hits detected: 207798 207855 207694 207459 207405 207339 207344 207535 207440 207345 207337 207424 207429 207663 207795 207884
[10:01:31.687] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.7 99.8 99.7 99.7 99.7 99.7 99.8 99.9 99.9 99.9
[10:01:31.687] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.7 99.7 99.7 99.7 99.8 99.7 99.7 99.7 99.7 99.7 99.8 99.9 99.9
[10:01:31.687] INFO: X-ray hit rate [MHz/cm2]: 29.8 28.2 48.8 73.3 77.8 79.2 78.4 72.7 80.5 79.5 76.3 73.4 73.7 48.0 28.4 24.0
[10:01:31.687] INFO: PixTestHighRate::doXPixelAlive() done
[10:01:31.733] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[10:01:31.733] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[10:01:31.733] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[10:01:31.733] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[10:01:31.733] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[10:01:31.733] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[10:01:31.733] INFO: PixTest:: pg_setup set to default.
[10:01:37.612] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[10:01:37.612] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[10:01:39.276] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:01:39.277] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 391.5mA
[10:01:39.278] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 479.1mA
[10:01:39.278] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[10:01:39.278] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:01:39.278] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:01:39.278] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:01:39.278] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:01:39.278] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:01:39.278] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:01:39.278] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:01:39.278] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:01:39.278] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:01:39.278] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:01:39.366] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:01:39.366] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:01:39.380] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:01:39.380] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:01:39.380] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:01:39.380] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:01:39.380] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:01:39.380] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:01:39.382] QUIET: Connection to board 58 closed.
[10:01:39.462] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[09:55:36.693] INFO: *** Welcome to pxar ***
[09:55:36.693] INFO: *** Today: 2016/08/01
[09:55:36.716] INFO: *** Version: v1.9.0-816-gf0c0
[09:55:36.716] INFO: readRocDacs: data/mp521/dacParameters35_C0.dat .. data/mp521/dacParameters35_C15.dat
[09:55:36.717] INFO: readTbmDacs: data/mp521/tbmParameters_C0a.dat .. data/mp521/tbmParameters_C0b.dat
[09:55:36.717] INFO: readMaskFile: data/mp521/defaultMaskFile.dat
[09:55:36.717] INFO: readTrimFile: data/mp521/trimParameters35_C0.dat .. data/mp521/trimParameters35_C15.dat
[09:55:36.865] INFO: clk: 4
[09:55:36.865] INFO: ctr: 4
[09:55:36.865] INFO: sda: 19
[09:55:36.865] INFO: tin: 9
[09:55:36.865] INFO: level: 15
[09:55:36.865] INFO: triggerdelay: 0
[09:55:36.865] QUIET: Instanciating API for pxar v1.9.0+816~gf0c098f
[09:55:36.865] INFO: Log level: DEBUG
[09:55:36.876] QUIET: Connection to board DTB_WRPRHI opened.
[09:55:36.879] 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:
------------------------------------------------------
[09:55:36.882] INFO: RPC call hashes of host and DTB match: 398089610
[09:55:38.481] INFO: DUT info:
[09:55:38.481] INFO: The DUT currently contains the following objects:
[09:55:38.481] INFO: 2 TBM Cores tbm08c (2 ON)
[09:55:38.481] INFO: TBM Core alpha (0): 7 registers set
[09:55:38.481] INFO: TBM Core beta (1): 7 registers set
[09:55:38.481] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:55:38.482] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[09:55:38.482] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[09:55:38.483] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:55:38.484] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:55:38.487] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31350784
[09:55:38.487] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1ed00e0
[09:55:38.487] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1c72370
[09:55:38.487] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fd4d9d94010
[09:55:38.487] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fd4dffff510
[09:55:38.487] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31358976 fPxarMemory = 0x7fd4d9d94010
[09:55:38.488] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 388.3mA
[09:55:38.489] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 478.3mA
[09:55:38.489] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[09:55:38.977] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x22bef60
[09:55:39.038] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[09:55:39.038] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[09:55:39.038] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[09:55:39.043] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[09:55:39.043] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[09:55:39.044] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[09:55:39.065] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[09:55:39.065] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[09:55:39.081] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[09:55:39.081] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[09:55:39.092] INFO: PixTestCmd::init()
[09:55:39.098] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[09:55:39.098] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[09:55:39.098] INFO: readGainPedestalParameters data/mp521/phCalibrationFitErr35_C0.dat .. data/mp521/phCalibrationFitErr35_C15.dat
[09:55:39.344] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[09:55:39.344] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[09:55:39.357] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[09:55:39.363] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[09:55:39.364] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[09:55:39.364] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[09:55:39.383] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[09:55:39.383] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[09:55:39.383] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[09:55:39.383] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[09:55:39.383] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[09:55:39.388] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[09:55:39.388] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[09:55:39.401] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[09:55:39.401] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[09:55:39.401] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[09:55:39.401] INFO: readReadbackCal: data/mp521/readbackCal_C0.dat .. data/mp521/readbackCal_C15.dat
[09:55:39.411] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[09:55:39.419] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[09:55:39.419] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[09:55:39.419] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[09:55:39.419] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[09:55:39.431] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[09:55:39.431] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[09:55:44.287] INFO: ######################################################################
[09:55:44.287] INFO: PixTestAlive::doTest()
[09:55:44.287] INFO: ######################################################################
[09:55:44.291] INFO: ----------------------------------------------------------------------
[09:55:44.291] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:55:44.291] INFO: ----------------------------------------------------------------------
[09:55:44.293] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:55:44.668] INFO: Expecting 41600 events.
[09:55:48.922] INFO: 41600 events read in total (3536ms).
[09:55:49.070] INFO: Test took 4777ms.
[09:55:49.079] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:49.079] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[09:55:49.079] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[09:55:49.348] INFO: PixTestAlive::aliveTest() done
[09:55:49.348] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:55:49.348] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 35 34 52 74 87 70 89 55 65 87 69 63 74 52 26 28
[09:55:49.370] INFO: ----------------------------------------------------------------------
[09:55:49.370] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:55:49.370] INFO: ----------------------------------------------------------------------
[09:55:49.372] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:55:49.746] INFO: Expecting 41600 events.
[09:55:52.789] INFO: 41600 events read in total (2328ms).
[09:55:52.789] INFO: Test took 3417ms.
[09:55:52.789] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:52.789] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[09:55:52.789] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[09:55:52.790] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:55:53.212] INFO: PixTestAlive::maskTest() done
[09:55:53.212] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:55:53.230] INFO: ----------------------------------------------------------------------
[09:55:53.230] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:55:53.230] INFO: ----------------------------------------------------------------------
[09:55:53.232] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:55:53.606] INFO: Expecting 41600 events.
[09:55:57.843] INFO: 41600 events read in total (3521ms).
[09:55:57.845] INFO: Test took 4613ms.
[09:55:57.855] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:57.855] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[09:55:57.855] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[09:55:58.227] INFO: PixTestAlive::addressDecodingTest() done
[09:55:58.227] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:55:58.228] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[09:56:01.852] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[09:56:01.852] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C3_V0 -> PixelAlive_mod
[09:56:05.380] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[09:56:05.380] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[09:56:05.380] INFO: ----------------------------------------------------------------------
[09:56:05.380] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[09:56:05.380] INFO: ----------------------------------------------------------------------
[09:56:05.519] INFO: Expecting 768 events.
[09:56:06.652] INFO: 768 events read in total (418ms).
[09:56:06.652] INFO: Test took 1266ms.
[09:56:06.657] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:56:07.456] INFO: Expecting 41600 events.
[09:56:11.137] INFO: 41600 events read in total (3154ms).
[09:56:11.142] INFO: Test took 4485ms.
[09:56:11.297] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:11.297] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 894077
[09:56:11.297] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[09:56:11.297] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:56:11.361] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:56:12.026] INFO: Expecting 41600 events.
[09:56:15.789] INFO: 41600 events read in total (3237ms).
[09:56:15.794] INFO: Test took 4433ms.
[09:56:15.939] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:15.939] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 902608
[09:56:15.939] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[09:56:15.939] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:56:16.007] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:56:16.672] INFO: Expecting 41600 events.
[09:56:20.459] INFO: 41600 events read in total (3260ms).
[09:56:20.464] INFO: Test took 4457ms.
[09:56:20.611] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:20.611] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904268
[09:56:20.611] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[09:56:20.612] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:56:20.679] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:56:21.345] INFO: Expecting 41600 events.
[09:56:25.140] INFO: 41600 events read in total (3269ms).
[09:56:25.145] INFO: Test took 4466ms.
[09:56:25.293] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:25.293] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 903813
[09:56:25.293] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[09:56:25.293] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:56:25.361] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:56:26.027] INFO: Expecting 41600 events.
[09:56:29.825] INFO: 41600 events read in total (3272ms).
[09:56:29.830] INFO: Test took 4468ms.
[09:56:29.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:29.979] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 902608
[09:56:29.979] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[09:56:29.979] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:56:30.047] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:56:30.711] INFO: Expecting 41600 events.
[09:56:34.515] INFO: 41600 events read in total (3277ms).
[09:56:34.520] INFO: Test took 4473ms.
[09:56:34.666] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:34.666] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 903269
[09:56:34.666] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[09:56:34.666] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:56:34.733] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:56:35.399] INFO: Expecting 41600 events.
[09:56:39.221] INFO: 41600 events read in total (3296ms).
[09:56:39.226] INFO: Test took 4492ms.
[09:56:39.370] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:39.370] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 905193
[09:56:39.370] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[09:56:39.371] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:56:39.438] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:56:40.103] INFO: Expecting 41600 events.
[09:56:43.923] INFO: 41600 events read in total (3293ms).
[09:56:43.928] INFO: Test took 4490ms.
[09:56:44.076] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:44.076] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 903921
[09:56:44.076] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[09:56:44.076] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:56:44.144] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:56:44.809] INFO: Expecting 41600 events.
[09:56:48.638] INFO: 41600 events read in total (3302ms).
[09:56:48.644] INFO: Test took 4500ms.
[09:56:48.791] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:48.791] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904109
[09:56:48.791] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[09:56:48.791] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:56:48.859] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:56:49.524] INFO: Expecting 41600 events.
[09:56:53.345] INFO: 41600 events read in total (3294ms).
[09:56:53.351] INFO: Test took 4491ms.
[09:56:53.497] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:53.497] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 905054
[09:56:53.497] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[09:56:53.497] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:56:53.565] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:56:54.230] INFO: Expecting 41600 events.
[09:56:58.046] INFO: 41600 events read in total (3289ms).
[09:56:58.051] INFO: Test took 4486ms.
[09:56:58.195] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:56:58.196] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 903547
[09:56:58.196] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[09:56:58.196] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:56:58.264] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:56:58.929] INFO: Expecting 41600 events.
[09:57:02.754] INFO: 41600 events read in total (3298ms).
[09:57:02.759] INFO: Test took 4495ms.
[09:57:02.908] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:02.908] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904246
[09:57:02.908] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[09:57:02.908] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:57:02.976] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:57:03.641] INFO: Expecting 41600 events.
[09:57:07.473] INFO: 41600 events read in total (3305ms).
[09:57:07.478] INFO: Test took 4502ms.
[09:57:07.625] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:07.625] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 906001
[09:57:07.625] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[09:57:07.626] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:57:07.694] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:57:08.360] INFO: Expecting 41600 events.
[09:57:12.176] INFO: 41600 events read in total (3289ms).
[09:57:12.182] INFO: Test took 4488ms.
[09:57:12.328] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:12.329] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 905013
[09:57:12.329] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[09:57:12.329] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:57:12.397] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:57:13.062] INFO: Expecting 41600 events.
[09:57:16.876] INFO: 41600 events read in total (3288ms).
[09:57:16.881] INFO: Test took 4484ms.
[09:57:17.030] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:17.030] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 903600
[09:57:17.030] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[09:57:17.030] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:57:17.099] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:57:17.765] INFO: Expecting 41600 events.
[09:57:21.587] INFO: 41600 events read in total (3295ms).
[09:57:21.592] INFO: Test took 4493ms.
[09:57:21.737] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:21.737] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904321
[09:57:21.737] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[09:57:21.738] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:57:21.805] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:57:22.470] INFO: Expecting 41600 events.
[09:57:26.291] INFO: 41600 events read in total (3294ms).
[09:57:26.296] INFO: Test took 4490ms.
[09:57:26.444] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:26.444] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904102
[09:57:26.444] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[09:57:26.444] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:57:26.514] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:57:27.180] INFO: Expecting 41600 events.
[09:57:31.001] INFO: 41600 events read in total (3295ms).
[09:57:31.006] INFO: Test took 4492ms.
[09:57:31.151] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:31.152] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904824
[09:57:31.152] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[09:57:31.152] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:57:31.221] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:57:31.886] INFO: Expecting 41600 events.
[09:57:35.667] INFO: 41600 events read in total (3254ms).
[09:57:35.672] INFO: Test took 4451ms.
[09:57:35.818] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:35.818] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 903506
[09:57:35.818] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[09:57:35.819] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:57:35.886] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:57:36.551] INFO: Expecting 41600 events.
[09:57:40.150] INFO: 41600 events read in total (3072ms).
[09:57:40.155] INFO: Test took 4269ms.
[09:57:40.298] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:57:40.298] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 893869
[09:57:40.298] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[09:57:40.299] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:57:40.586] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 140 eff = 0.99887
[09:57:40.586] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 155 eff = 0.999279
[09:57:40.587] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 169 eff = 0.997981
[09:57:40.587] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 169 eff = 0.995721
[09:57:40.587] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 161 eff = 0.995264
[09:57:40.587] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 173 eff = 0.99488
[09:57:40.587] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 165 eff = 0.99524
[09:57:40.587] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 172 eff = 0.996298
[09:57:40.587] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 200 eff = 0.995144
[09:57:40.588] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 174 eff = 0.995457
[09:57:40.588] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 164 eff = 0.995697
[09:57:40.588] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 182 eff = 0.995817
[09:57:40.588] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 183 eff = 0.995313
[09:57:40.588] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 157 eff = 0.997909
[09:57:40.589] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 153 eff = 0.999063
[09:57:40.589] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 186 eff = 0.999279
[09:57:43.252] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[09:57:43.252] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[09:57:43.252] INFO: ----------------------------------------------------------------------
[09:57:43.252] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:57:43.252] INFO: ----------------------------------------------------------------------
[09:57:43.252] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[09:57:43.253] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[09:57:43.253] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[09:57:43.253] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[09:57:43.253] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[09:57:43.253] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[09:57:43.253] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[09:57:43.253] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[09:57:43.253] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[09:57:43.253] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[09:57:43.253] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[09:57:43.253] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[09:57:43.268] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:57:43.933] INFO: Expecting 208000 events.
[09:58:00.001] INFO: 208000 events read in total (15542ms).
[09:58:00.021] INFO: Test took 16753ms.
[09:58:00.769] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:00.769] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4210128
[09:58:00.769] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[09:58:00.770] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:58:01.273] INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:58:01.273] INFO: number of red-efficiency pixels: 218 188 475 711 884 881 852 719 825 910 766 811 789 415 196 172
[09:58:01.273] INFO: number of X-ray hits detected: 127869 119986 208063 313302 331683 338400 334146 309711 343754 337933 324135 313448 313527 203532 121438 102642
[09:58:01.273] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:58:01.273] INFO: number of Vcal hits detected: 207725 207805 207468 207184 206960 206977 206991 207189 207025 206934 207115 207043 207052 207534 207795 207819
[09:58:01.273] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.6 99.5 99.5 99.6 99.6 99.6 99.5 99.6 99.6 99.6 99.8 99.9 99.9
[09:58:01.273] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.6 99.5 99.5 99.5 99.6 99.5 99.5 99.6 99.5 99.5 99.8 99.9 99.9
[09:58:01.273] INFO: X-ray hit rate [MHz/cm2]: 37.5 35.2 61.0 91.8 97.2 99.2 97.9 90.8 100.8 99.1 95.0 91.9 91.9 59.7 35.6 30.1
[09:58:01.273] INFO: PixTestHighRate::doXPixelAlive() done
[09:58:01.318] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[09:58:01.318] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[09:58:01.318] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[09:58:01.318] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[09:58:01.318] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[09:58:01.318] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[09:58:01.318] INFO: PixTest:: pg_setup set to default.
[09:58:07.900] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[09:58:07.900] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[09:58:09.572] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[09:58:09.573] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 391.5mA
[09:58:09.574] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 479.1mA
[09:58:09.574] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[09:58:09.574] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[09:58:09.574] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[09:58:09.574] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[09:58:09.574] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[09:58:09.574] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[09:58:09.574] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[09:58:09.574] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[09:58:09.574] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[09:58:09.574] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[09:58:09.574] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[09:58:09.665] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[09:58:09.665] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[09:58:09.679] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[09:58:09.679] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[09:58:09.679] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[09:58:09.679] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[09:58:09.679] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[09:58:09.679] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[09:58:09.682] QUIET: Connection to board 58 closed.
[09:58:09.761] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries