Test Date: 1973-11-29 16:33
Analysis date: 2016-06-14 17:36
Logfile
hrData_40.log
[09:52:53.150] INFO: *** Welcome to pxar ***
[09:52:53.150] INFO: *** Today: 2016/06/14
[09:52:53.174] INFO: *** Version: v1.9.0-814-g7497
[09:52:53.175] INFO: readRocDacs: data/mp217/dacParameters35_C0.dat .. data/mp217/dacParameters35_C15.dat
[09:52:53.175] INFO: readTbmDacs: data/mp217/tbmParameters_C0a.dat .. data/mp217/tbmParameters_C0b.dat
[09:52:53.176] INFO: readMaskFile: data/mp217/defaultMaskFile.dat
[09:52:53.176] INFO: readTrimFile: data/mp217/trimParameters35_C0.dat .. data/mp217/trimParameters35_C15.dat
[09:52:53.325] INFO: clk: 4
[09:52:53.325] INFO: ctr: 4
[09:52:53.325] INFO: sda: 19
[09:52:53.325] INFO: tin: 9
[09:52:53.325] INFO: level: 15
[09:52:53.325] INFO: triggerdelay: 0
[09:52:53.325] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[09:52:53.325] INFO: Log level: DEBUG
[09:52:53.336] QUIET: Connection to board DTB_WRMNMR opened.
[09:52:53.340] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[09:52:53.342] INFO: RPC call hashes of host and DTB match: 398089610
[09:52:54.944] INFO: DUT info:
[09:52:54.944] INFO: The DUT currently contains the following objects:
[09:52:54.944] INFO: 2 TBM Cores tbm08c (2 ON)
[09:52:54.944] INFO: TBM Core alpha (0): 7 registers set
[09:52:54.944] INFO: TBM Core beta (1): 7 registers set
[09:52:54.944] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:52:54.944] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.944] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.944] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.944] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.944] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.944] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.944] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.945] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.945] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.945] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.945] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.945] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.945] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.945] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.945] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.945] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[09:52:54.945] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[09:52:54.946] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[09:52:54.947] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:52:54.948] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:52:54.950] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31404032
[09:52:54.950] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xcae0d0
[09:52:54.950] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xa50360
[09:52:54.950] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fb595d94010
[09:52:54.950] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fb59bfff510
[09:52:54.950] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31412224 fPxarMemory = 0x7fb595d94010
[09:52:54.951] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 377.8mA
[09:52:54.952] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 464.7mA
[09:52:54.952] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[09:52:55.449] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1098cf0
[09:52:55.514] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[09:52:55.515] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[09:52:55.515] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[09:52:55.520] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[09:52:55.520] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[09:52:55.520] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[09:52:55.531] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[09:52:55.531] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[09:52:55.553] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[09:52:55.554] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[09:52:55.574] INFO: PixTestCmd::init()
[09:52:55.587] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[09:52:55.587] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[09:52:55.587] INFO: readGainPedestalParameters data/mp217/phCalibrationFitErr35_C0.dat .. data/mp217/phCalibrationFitErr35_C15.dat
[09:52:55.833] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[09:52:55.834] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[09:52:55.848] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[09:52:55.856] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[09:52:55.856] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[09:52:55.856] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[09:52:55.877] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[09:52:55.877] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[09:52:55.877] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[09:52:55.877] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[09:52:55.877] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[09:52:55.883] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[09:52:55.883] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[09:52:55.898] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[09:52:55.898] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[09:52:55.898] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[09:52:55.898] INFO: readReadbackCal: data/mp217/readbackCal_C0.dat .. data/mp217/readbackCal_C15.dat
[09:52:55.908] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[09:52:55.915] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[09:52:55.916] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[09:52:55.916] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[09:52:55.916] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[09:52:55.932] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[09:52:55.932] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[09:52:59.565] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[09:53:01.357] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[09:53:02.706] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[09:53:02.707] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[09:53:02.707] INFO: ----------------------------------------------------------------------
[09:53:02.707] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[09:53:02.707] INFO: ----------------------------------------------------------------------
[09:53:03.680] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[09:53:16.763] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[09:53:16.767] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[09:53:44.142] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1289092 events.
[09:53:49.354] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1289092, pixels seen in all events: 4782691
[09:53:49.453] INFO: Resuming triggers.
[09:54:02.520] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[09:54:02.551] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[09:54:29.873] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1287400 events.
[09:54:35.121] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1287400, pixels seen in all events: 4802151
[09:54:35.216] INFO: Resuming triggers.
[09:54:48.284] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[09:54:48.380] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[09:55:15.728] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1287437 events.
[09:55:20.888] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1287437, pixels seen in all events: 4802067
[09:55:21.108] INFO: Resuming triggers.
[09:55:34.181] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[09:55:34.221] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[09:56:01.563] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1287895 events.
[09:56:06.757] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1287895, pixels seen in all events: 4810057
[09:56:06.977] INFO: Resuming triggers.
[09:56:14.763] INFO: data taking finished, elapsed time: 60 seconds.
[09:56:14.959] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[09:56:31.238] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 766945 events.
[09:56:34.313] DEBUG: <PixTestXray.cc/processData:L823> # events read: 766945, pixels seen in all events: 2854528
[09:56:34.384] INFO: PixTest:: pg_setup set to default.
[09:56:34.460] INFO: PixTestXray::doPhRun() done
[09:56:41.843] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[09:56:41.843] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 380.2mA
[09:56:41.844] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 464.7mA
[09:56:41.844] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[09:56:41.844] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[09:56:41.844] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[09:56:41.844] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[09:56:41.844] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[09:56:41.844] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[09:56:41.845] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[09:56:41.845] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[09:56:41.845] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[09:56:41.845] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[09:56:41.845] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[09:56:41.845] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[09:56:41.845] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[09:56:41.845] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[09:56:41.845] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[09:56:41.845] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[09:56:41.845] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[09:56:41.845] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[09:56:41.845] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[09:56:42.114] QUIET: Connection to board 43 closed.
[09:56:42.194] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[09:56:46.477] INFO: *** Welcome to pxar ***
[09:56:46.477] INFO: *** Today: 2016/06/14
[09:56:46.502] INFO: *** Version: v1.9.0-814-g7497
[09:56:46.502] INFO: readRocDacs: data/mp217/dacParameters35_C0.dat .. data/mp217/dacParameters35_C15.dat
[09:56:46.503] INFO: readTbmDacs: data/mp217/tbmParameters_C0a.dat .. data/mp217/tbmParameters_C0b.dat
[09:56:46.503] INFO: readMaskFile: data/mp217/defaultMaskFile.dat
[09:56:46.504] INFO: readTrimFile: data/mp217/trimParameters35_C0.dat .. data/mp217/trimParameters35_C15.dat
[09:56:46.652] INFO: clk: 4
[09:56:46.652] INFO: ctr: 4
[09:56:46.652] INFO: sda: 19
[09:56:46.652] INFO: tin: 9
[09:56:46.652] INFO: level: 15
[09:56:46.652] INFO: triggerdelay: 0
[09:56:46.652] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[09:56:46.652] INFO: Log level: DEBUG
[09:56:46.663] QUIET: Connection to board DTB_WRMNMR opened.
[09:56:46.666] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[09:56:46.669] INFO: RPC call hashes of host and DTB match: 398089610
[09:56:48.272] INFO: DUT info:
[09:56:48.272] INFO: The DUT currently contains the following objects:
[09:56:48.272] INFO: 2 TBM Cores tbm08c (2 ON)
[09:56:48.272] INFO: TBM Core alpha (0): 7 registers set
[09:56:48.272] INFO: TBM Core beta (1): 7 registers set
[09:56:48.272] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:56:48.272] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.272] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.272] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.272] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.272] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.272] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.272] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.272] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.272] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.272] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.272] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.272] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.272] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.272] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.272] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.272] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[09:56:48.273] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:56:48.274] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[09:56:48.275] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:56:48.276] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:56:48.278] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31387648
[09:56:48.278] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x14d80d0
[09:56:48.278] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x127a360
[09:56:48.278] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f3ee1d94010
[09:56:48.278] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f3ee7fff510
[09:56:48.278] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31395840 fPxarMemory = 0x7f3ee1d94010
[09:56:48.279] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 377.8mA
[09:56:48.281] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 464.7mA
[09:56:48.281] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[09:56:48.777] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x18c2db0
[09:56:48.844] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[09:56:48.844] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[09:56:48.845] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[09:56:48.850] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[09:56:48.850] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[09:56:48.850] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[09:56:48.861] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[09:56:48.861] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[09:56:48.887] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[09:56:48.887] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[09:56:48.905] INFO: PixTestCmd::init()
[09:56:48.916] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[09:56:48.916] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[09:56:48.916] INFO: readGainPedestalParameters data/mp217/phCalibrationFitErr35_C0.dat .. data/mp217/phCalibrationFitErr35_C15.dat
[09:56:49.162] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[09:56:49.162] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[09:56:49.176] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[09:56:49.182] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[09:56:49.182] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[09:56:49.182] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[09:56:49.203] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[09:56:49.203] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[09:56:49.203] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[09:56:49.203] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[09:56:49.203] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[09:56:49.210] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[09:56:49.210] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[09:56:49.224] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[09:56:49.224] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[09:56:49.224] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[09:56:49.224] INFO: readReadbackCal: data/mp217/readbackCal_C0.dat .. data/mp217/readbackCal_C15.dat
[09:56:49.238] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[09:56:49.247] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[09:56:49.247] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[09:56:49.247] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[09:56:49.247] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[09:56:49.265] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[09:56:49.265] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[09:56:57.774] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[09:57:00.894] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[09:57:02.610] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[09:57:02.611] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[09:57:02.611] INFO: ----------------------------------------------------------------------
[09:57:02.611] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[09:57:02.611] INFO: ----------------------------------------------------------------------
[09:57:03.584] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[09:57:11.433] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[09:57:11.437] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[09:57:36.916] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 773336 events.
[09:57:48.789] DEBUG: <PixTestXray.cc/processData:L823> # events read: 773336, pixels seen in all events: 10972800
[09:57:49.046] INFO: Resuming triggers.
[09:57:56.902] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[09:57:56.939] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[09:58:22.329] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 773863 events.
[09:58:34.080] DEBUG: <PixTestXray.cc/processData:L823> # events read: 773863, pixels seen in all events: 10965096
[09:58:34.281] INFO: Resuming triggers.
[09:58:42.135] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[09:58:42.201] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[09:59:07.560] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 773680 events.
[09:59:19.340] DEBUG: <PixTestXray.cc/processData:L823> # events read: 773680, pixels seen in all events: 10967144
[09:59:19.577] INFO: Resuming triggers.
[09:59:27.435] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[09:59:27.470] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[09:59:52.872] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 774098 events.
[10:00:04.615] DEBUG: <PixTestXray.cc/processData:L823> # events read: 774098, pixels seen in all events: 10963086
[10:00:04.802] INFO: Resuming triggers.
[10:00:12.661] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[10:00:12.739] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:00:38.178] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 774222 events.
[10:00:49.909] DEBUG: <PixTestXray.cc/processData:L823> # events read: 774222, pixels seen in all events: 10961059
[10:00:50.116] INFO: Resuming triggers.
[10:00:57.972] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[10:00:58.010] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:01:23.325] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 773899 events.
[10:01:35.056] DEBUG: <PixTestXray.cc/processData:L823> # events read: 773899, pixels seen in all events: 10964599
[10:01:35.295] INFO: Resuming triggers.
[10:01:43.148] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[10:01:43.185] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:02:08.704] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 773666 events.
[10:02:20.499] DEBUG: <PixTestXray.cc/processData:L823> # events read: 773666, pixels seen in all events: 10967641
[10:02:20.740] INFO: Resuming triggers.
[10:02:25.838] INFO: data taking finished, elapsed time: 60 seconds.
[10:02:26.034] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:02:42.564] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 502169 events.
[10:02:50.196] DEBUG: <PixTestXray.cc/processData:L823> # events read: 502169, pixels seen in all events: 7116449
[10:02:50.319] INFO: PixTest:: pg_setup set to default.
[10:02:50.359] INFO: PixTestXray::doPhRun() done
[10:02:56.331] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:02:56.331] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 380.2mA
[10:02:56.332] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[10:02:56.332] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[10:02:56.332] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:02:56.332] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:02:56.332] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:02:56.332] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:02:56.332] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:02:56.333] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:02:56.333] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:02:56.333] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:02:56.333] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:02:56.333] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:02:56.333] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:02:56.333] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:02:56.333] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:02:56.333] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:02:56.333] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:02:56.333] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:02:56.333] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:02:56.333] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:02:56.638] QUIET: Connection to board 43 closed.
[10:02:56.718] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[09:48:13.894] INFO: *** Welcome to pxar ***
[09:48:13.894] INFO: *** Today: 2016/06/14
[09:48:13.919] INFO: *** Version: v1.9.0-814-g7497
[09:48:13.919] INFO: readRocDacs: data/mp217/dacParameters35_C0.dat .. data/mp217/dacParameters35_C15.dat
[09:48:13.920] INFO: readTbmDacs: data/mp217/tbmParameters_C0a.dat .. data/mp217/tbmParameters_C0b.dat
[09:48:13.920] INFO: readMaskFile: data/mp217/defaultMaskFile.dat
[09:48:13.920] INFO: readTrimFile: data/mp217/trimParameters35_C0.dat .. data/mp217/trimParameters35_C15.dat
[09:48:14.073] INFO: clk: 4
[09:48:14.073] INFO: ctr: 4
[09:48:14.073] INFO: sda: 19
[09:48:14.073] INFO: tin: 9
[09:48:14.073] INFO: level: 15
[09:48:14.073] INFO: triggerdelay: 0
[09:48:14.073] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[09:48:14.073] INFO: Log level: DEBUG
[09:48:14.084] QUIET: Connection to board DTB_WRMNMR opened.
[09:48:14.088] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[09:48:14.090] INFO: RPC call hashes of host and DTB match: 398089610
[09:48:15.693] INFO: DUT info:
[09:48:15.693] INFO: The DUT currently contains the following objects:
[09:48:15.693] INFO: 2 TBM Cores tbm08c (2 ON)
[09:48:15.693] INFO: TBM Core alpha (0): 7 registers set
[09:48:15.693] INFO: TBM Core beta (1): 7 registers set
[09:48:15.693] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:48:15.693] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.693] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.693] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.693] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.693] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.693] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.693] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.693] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.693] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.693] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.693] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.693] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.694] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.694] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.694] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.694] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[09:48:15.694] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[09:48:15.695] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[09:48:15.696] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:48:15.697] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:48:15.699] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34848768
[09:48:15.699] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x10bc0d0
[09:48:15.699] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xe5e360
[09:48:15.699] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fa435d94010
[09:48:15.699] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fa43bfff510
[09:48:15.699] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34856960 fPxarMemory = 0x7fa435d94010
[09:48:15.700] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 377.8mA
[09:48:15.702] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 463.9mA
[09:48:15.702] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[09:48:16.198] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x14a6cf0
[09:48:16.268] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[09:48:16.268] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[09:48:16.268] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[09:48:16.274] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[09:48:16.274] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[09:48:16.274] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[09:48:16.284] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[09:48:16.284] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[09:48:16.292] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[09:48:16.292] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[09:48:16.317] INFO: PixTestCmd::init()
[09:48:16.322] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[09:48:16.322] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[09:48:16.322] INFO: readGainPedestalParameters data/mp217/phCalibrationFitErr35_C0.dat .. data/mp217/phCalibrationFitErr35_C15.dat
[09:48:16.568] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[09:48:16.568] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[09:48:16.583] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[09:48:16.590] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[09:48:16.590] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[09:48:16.590] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[09:48:16.613] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[09:48:16.614] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[09:48:16.614] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[09:48:16.614] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[09:48:16.614] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[09:48:16.620] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[09:48:16.620] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[09:48:16.640] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[09:48:16.640] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[09:48:16.640] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[09:48:16.640] INFO: readReadbackCal: data/mp217/readbackCal_C0.dat .. data/mp217/readbackCal_C15.dat
[09:48:16.649] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[09:48:16.658] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[09:48:16.658] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[09:48:16.658] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[09:48:16.659] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[09:48:16.674] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[09:48:16.674] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[09:48:20.766] INFO: ######################################################################
[09:48:20.766] INFO: PixTestAlive::doTest()
[09:48:20.766] INFO: ######################################################################
[09:48:20.769] INFO: ----------------------------------------------------------------------
[09:48:20.769] 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:48:20.769] INFO: ----------------------------------------------------------------------
[09:48:20.772] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:48:21.151] INFO: Expecting 41600 events.
[09:48:25.371] INFO: 41600 events read in total (3502ms).
[09:48:25.518] INFO: Test took 4746ms.
[09:48:25.529] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:48:25.529] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[09:48:25.530] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[09:48:25.800] INFO: PixTestAlive::aliveTest() done
[09:48:25.800] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:48:25.800] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 22 19 21 36 25 39 38 39 44 26 40 47 43 36 17 14
[09:48:25.826] INFO: ----------------------------------------------------------------------
[09:48:25.826] 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:48:25.826] INFO: ----------------------------------------------------------------------
[09:48:25.830] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:48:26.209] INFO: Expecting 41600 events.
[09:48:29.147] INFO: 41600 events read in total (2223ms).
[09:48:29.147] INFO: Test took 3317ms.
[09:48:29.147] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:48:29.147] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[09:48:29.147] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[09:48:29.148] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:48:29.570] INFO: PixTestAlive::maskTest() done
[09:48:29.570] 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:48:29.591] INFO: ----------------------------------------------------------------------
[09:48:29.591] 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:48:29.591] INFO: ----------------------------------------------------------------------
[09:48:29.594] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:48:29.973] INFO: Expecting 41600 events.
[09:48:34.178] INFO: 41600 events read in total (3490ms).
[09:48:34.181] INFO: Test took 4587ms.
[09:48:34.191] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:48:34.191] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[09:48:34.191] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[09:48:34.560] INFO: PixTestAlive::addressDecodingTest() done
[09:48:34.561] 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:48:34.561] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[09:48:38.074] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[09:48:38.074] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[09:48:38.075] INFO: ----------------------------------------------------------------------
[09:48:38.075] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[09:48:38.075] INFO: ----------------------------------------------------------------------
[09:48:38.214] INFO: Expecting 768 events.
[09:48:39.342] INFO: 768 events read in total (413ms).
[09:48:39.342] INFO: Test took 1262ms.
[09:48:39.348] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:48:40.145] INFO: Expecting 41600 events.
[09:48:43.337] INFO: 41600 events read in total (2665ms).
[09:48:43.342] INFO: Test took 3994ms.
[09:48:43.424] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:48:43.424] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464037
[09:48:43.424] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[09:48:43.424] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:48:43.462] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:48:44.127] INFO: Expecting 41600 events.
[09:48:47.444] INFO: 41600 events read in total (2790ms).
[09:48:47.447] INFO: Test took 3985ms.
[09:48:47.524] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:48:47.524] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 471372
[09:48:47.524] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[09:48:47.524] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:48:47.563] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:48:48.228] INFO: Expecting 41600 events.
[09:48:51.579] INFO: 41600 events read in total (2824ms).
[09:48:51.583] INFO: Test took 4020ms.
[09:48:51.661] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:48:51.661] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 471640
[09:48:51.661] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[09:48:51.662] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:48:51.701] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:48:52.367] INFO: Expecting 41600 events.
[09:48:55.710] INFO: 41600 events read in total (2816ms).
[09:48:55.714] INFO: Test took 4013ms.
[09:48:55.792] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:48:55.792] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 472171
[09:48:55.792] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[09:48:55.793] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:48:55.832] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:48:56.499] INFO: Expecting 41600 events.
[09:48:59.846] INFO: 41600 events read in total (2820ms).
[09:48:59.850] INFO: Test took 4018ms.
[09:48:59.928] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:48:59.929] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 472130
[09:48:59.929] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[09:48:59.929] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:48:59.969] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:49:00.634] INFO: Expecting 41600 events.
[09:49:03.991] INFO: 41600 events read in total (2830ms).
[09:49:03.994] INFO: Test took 4025ms.
[09:49:04.072] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:04.072] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 472901
[09:49:04.072] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[09:49:04.073] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:49:04.112] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:49:04.777] INFO: Expecting 41600 events.
[09:49:08.131] INFO: 41600 events read in total (2828ms).
[09:49:08.134] INFO: Test took 4022ms.
[09:49:08.214] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:08.214] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 471509
[09:49:08.214] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[09:49:08.214] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:49:08.253] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:49:08.918] INFO: Expecting 41600 events.
[09:49:12.279] INFO: 41600 events read in total (2834ms).
[09:49:12.282] INFO: Test took 4029ms.
[09:49:12.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:12.360] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 472973
[09:49:12.360] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[09:49:12.360] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:49:12.399] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:49:13.064] INFO: Expecting 41600 events.
[09:49:16.415] INFO: 41600 events read in total (2824ms).
[09:49:16.419] INFO: Test took 4020ms.
[09:49:16.496] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:16.496] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 473226
[09:49:16.496] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[09:49:16.497] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:49:16.536] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:49:17.201] INFO: Expecting 41600 events.
[09:49:20.553] INFO: 41600 events read in total (2825ms).
[09:49:20.556] INFO: Test took 4020ms.
[09:49:20.635] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:20.635] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 473121
[09:49:20.635] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[09:49:20.635] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:49:20.675] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:49:21.340] INFO: Expecting 41600 events.
[09:49:24.688] INFO: 41600 events read in total (2821ms).
[09:49:24.692] INFO: Test took 4017ms.
[09:49:24.769] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:24.769] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 472397
[09:49:24.769] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[09:49:24.770] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:49:24.810] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:49:25.475] INFO: Expecting 41600 events.
[09:49:28.824] INFO: 41600 events read in total (2823ms).
[09:49:28.828] INFO: Test took 4018ms.
[09:49:28.905] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:28.905] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 472848
[09:49:28.906] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[09:49:28.906] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:49:28.945] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:49:29.609] INFO: Expecting 41600 events.
[09:49:32.961] INFO: 41600 events read in total (2825ms).
[09:49:32.965] INFO: Test took 4020ms.
[09:49:33.041] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:33.041] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 471398
[09:49:33.041] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[09:49:33.042] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:49:33.081] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:49:33.746] INFO: Expecting 41600 events.
[09:49:37.092] INFO: 41600 events read in total (2820ms).
[09:49:37.096] INFO: Test took 4015ms.
[09:49:37.173] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:37.173] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 471025
[09:49:37.173] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[09:49:37.173] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:49:37.212] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:49:37.877] INFO: Expecting 41600 events.
[09:49:41.229] INFO: 41600 events read in total (2825ms).
[09:49:41.233] INFO: Test took 4021ms.
[09:49:41.311] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:41.311] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 472177
[09:49:41.311] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[09:49:41.311] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:49:41.350] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:49:42.016] INFO: Expecting 41600 events.
[09:49:45.375] INFO: 41600 events read in total (2833ms).
[09:49:45.379] INFO: Test took 4028ms.
[09:49:45.456] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:45.456] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 471900
[09:49:45.456] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[09:49:45.457] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:49:45.496] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:49:46.161] INFO: Expecting 41600 events.
[09:49:49.525] INFO: 41600 events read in total (2838ms).
[09:49:49.529] INFO: Test took 4033ms.
[09:49:49.606] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:49.606] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 470516
[09:49:49.606] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[09:49:49.607] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:49:49.646] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:49:50.311] INFO: Expecting 41600 events.
[09:49:53.660] INFO: 41600 events read in total (2822ms).
[09:49:53.664] INFO: Test took 4018ms.
[09:49:53.741] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:53.741] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 471515
[09:49:53.741] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[09:49:53.741] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:49:53.781] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:49:54.446] INFO: Expecting 41600 events.
[09:49:57.796] INFO: 41600 events read in total (2823ms).
[09:49:57.800] INFO: Test took 4019ms.
[09:49:57.878] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:49:57.878] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 470040
[09:49:57.878] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[09:49:57.879] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:49:57.917] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:49:58.582] INFO: Expecting 41600 events.
[09:50:01.804] INFO: 41600 events read in total (2695ms).
[09:50:01.808] INFO: Test took 3891ms.
[09:50:01.886] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:01.886] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 466704
[09:50:01.886] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[09:50:01.886] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:50:02.146] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 165 eff = 0.999591
[09:50:02.146] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 165 eff = 0.999784
[09:50:02.146] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 180 eff = 0.999471
[09:50:02.146] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 175 eff = 0.999038
[09:50:02.147] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 183 eff = 0.999014
[09:50:02.147] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 149 eff = 0.999063
[09:50:02.147] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 173 eff = 0.998942
[09:50:02.147] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 160 eff = 0.999111
[09:50:02.148] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 146 eff = 0.998918
[09:50:02.148] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 166 eff = 0.998894
[09:50:02.148] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 165 eff = 0.999111
[09:50:02.148] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 161 eff = 0.999087
[09:50:02.148] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 152 eff = 0.999087
[09:50:02.148] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 160 eff = 0.999447
[09:50:02.149] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 148 eff = 0.999712
[09:50:02.149] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 140 eff = 0.999712
[09:50:11.306] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[09:50:11.307] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[09:50:11.307] INFO: ----------------------------------------------------------------------
[09:50:11.307] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:50:11.307] INFO: ----------------------------------------------------------------------
[09:50:11.307] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[09:50:11.307] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[09:50:11.307] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[09:50:11.307] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[09:50:11.307] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[09:50:11.307] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[09:50:11.307] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[09:50:11.307] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[09:50:11.307] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[09:50:11.307] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[09:50:11.307] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[09:50:11.307] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[09:50:11.325] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:50:11.995] INFO: Expecting 208000 events.
[09:50:25.247] INFO: 208000 events read in total (12726ms).
[09:50:25.259] INFO: Test took 13934ms.
[09:50:25.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:50:25.617] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2077831
[09:50:25.617] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[09:50:25.617] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:50:25.984] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:50:25.984] INFO: number of red-efficiency pixels: 95 61 160 224 201 241 220 262 280 254 260 244 265 141 73 114
[09:50:25.984] INFO: number of X-ray hits detected: 77524 57458 98024 146819 157031 156893 155695 146869 161666 159973 160779 148585 149074 98099 61644 75138
[09:50:25.984] 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:50:25.984] INFO: number of Vcal hits detected: 207902 207939 207834 207767 207790 207747 207773 207732 207711 207732 207729 207745 207725 207855 207927 207886
[09:50:25.984] 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 99.9
[09:50:25.984] INFO: Vcal hit overall 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 99.9
[09:50:25.984] INFO: X-ray hit rate [MHz/cm2]: 22.7 16.8 28.7 43.0 46.0 46.0 45.6 43.0 47.4 46.9 47.1 43.6 43.7 28.8 18.1 22.0
[09:50:25.984] INFO: PixTestHighRate::doXPixelAlive() done
[09:50:26.029] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[09:50:26.029] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[09:50:26.029] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[09:50:26.029] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[09:50:26.029] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[09:50:26.029] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[09:50:26.029] INFO: PixTest:: pg_setup set to default.
[09:50:30.787] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[09:50:30.787] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[09:50:32.778] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[09:50:32.779] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 380.2mA
[09:50:32.780] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 464.7mA
[09:50:32.780] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[09:50:32.780] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[09:50:32.780] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[09:50:32.780] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[09:50:32.780] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[09:50:32.780] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[09:50:32.781] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[09:50:32.781] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[09:50:32.781] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[09:50:32.781] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[09:50:32.781] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[09:50:32.870] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[09:50:32.870] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[09:50:32.879] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[09:50:32.879] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[09:50:32.879] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[09:50:32.879] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[09:50:32.879] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[09:50:32.879] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[09:50:32.881] QUIET: Connection to board 43 closed.
[09:50:32.961] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[09:45:34.603] INFO: *** Welcome to pxar ***
[09:45:34.603] INFO: *** Today: 2016/06/14
[09:45:34.627] INFO: *** Version: v1.9.0-814-g7497
[09:45:34.627] INFO: readRocDacs: data/mp217/dacParameters35_C0.dat .. data/mp217/dacParameters35_C15.dat
[09:45:34.628] INFO: readTbmDacs: data/mp217/tbmParameters_C0a.dat .. data/mp217/tbmParameters_C0b.dat
[09:45:34.628] INFO: readMaskFile: data/mp217/defaultMaskFile.dat
[09:45:34.628] INFO: readTrimFile: data/mp217/trimParameters35_C0.dat .. data/mp217/trimParameters35_C15.dat
[09:45:34.779] INFO: clk: 4
[09:45:34.779] INFO: ctr: 4
[09:45:34.779] INFO: sda: 19
[09:45:34.779] INFO: tin: 9
[09:45:34.779] INFO: level: 15
[09:45:34.779] INFO: triggerdelay: 0
[09:45:34.779] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[09:45:34.779] INFO: Log level: DEBUG
[09:45:34.790] QUIET: Connection to board DTB_WRMNMR opened.
[09:45:34.793] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[09:45:34.796] INFO: RPC call hashes of host and DTB match: 398089610
[09:45:36.399] INFO: DUT info:
[09:45:36.399] INFO: The DUT currently contains the following objects:
[09:45:36.399] INFO: 2 TBM Cores tbm08c (2 ON)
[09:45:36.399] INFO: TBM Core alpha (0): 7 registers set
[09:45:36.399] INFO: TBM Core beta (1): 7 registers set
[09:45:36.399] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:45:36.399] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.399] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.399] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.399] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.399] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.399] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.399] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.400] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.400] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.400] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.400] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.400] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.400] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.400] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.400] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.400] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[09:45:36.400] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[09:45:36.401] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[09:45:36.402] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:45:36.403] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:45:36.405] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32768000
[09:45:36.405] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1e3bbd0
[09:45:36.405] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1bdde60
[09:45:36.405] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f0ec1d94010
[09:45:36.405] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f0ec7fff510
[09:45:36.406] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32776192 fPxarMemory = 0x7f0ec1d94010
[09:45:36.407] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 377mA
[09:45:36.408] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 463.9mA
[09:45:36.408] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[09:45:36.902] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2226800
[09:45:36.967] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[09:45:36.968] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[09:45:36.968] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[09:45:36.974] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[09:45:36.975] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[09:45:36.975] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[09:45:36.996] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[09:45:36.996] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[09:45:37.014] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[09:45:37.014] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[09:45:37.031] INFO: PixTestCmd::init()
[09:45:37.037] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[09:45:37.037] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[09:45:37.037] INFO: readGainPedestalParameters data/mp217/phCalibrationFitErr35_C0.dat .. data/mp217/phCalibrationFitErr35_C15.dat
[09:45:37.285] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[09:45:37.285] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[09:45:37.299] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[09:45:37.304] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[09:45:37.304] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[09:45:37.304] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[09:45:37.325] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[09:45:37.325] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[09:45:37.325] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[09:45:37.326] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[09:45:37.326] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[09:45:37.332] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[09:45:37.332] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[09:45:37.344] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[09:45:37.344] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[09:45:37.344] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[09:45:37.344] INFO: readReadbackCal: data/mp217/readbackCal_C0.dat .. data/mp217/readbackCal_C15.dat
[09:45:37.357] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[09:45:37.364] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[09:45:37.364] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[09:45:37.364] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[09:45:37.364] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[09:45:37.384] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[09:45:37.384] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[09:45:43.102] INFO: ######################################################################
[09:45:43.102] INFO: PixTestAlive::doTest()
[09:45:43.102] INFO: ######################################################################
[09:45:43.105] INFO: ----------------------------------------------------------------------
[09:45:43.105] 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:45:43.105] INFO: ----------------------------------------------------------------------
[09:45:43.108] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:45:43.490] INFO: Expecting 41600 events.
[09:45:47.699] INFO: 41600 events read in total (3491ms).
[09:45:47.846] INFO: Test took 4738ms.
[09:45:47.857] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:45:47.857] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[09:45:47.857] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[09:45:48.136] INFO: PixTestAlive::aliveTest() done
[09:45:48.136] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:45:48.136] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 29 16 39 57 56 54 46 57 69 51 56 54 52 32 21 41
[09:45:48.162] INFO: ----------------------------------------------------------------------
[09:45:48.162] 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:45:48.162] INFO: ----------------------------------------------------------------------
[09:45:48.165] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:45:48.541] INFO: Expecting 41600 events.
[09:45:51.480] INFO: 41600 events read in total (2223ms).
[09:45:51.480] INFO: Test took 3315ms.
[09:45:51.480] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:45:51.480] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[09:45:51.481] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[09:45:51.481] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:45:51.903] INFO: PixTestAlive::maskTest() done
[09:45:51.903] 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:45:51.923] INFO: ----------------------------------------------------------------------
[09:45:51.923] 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:45:51.923] INFO: ----------------------------------------------------------------------
[09:45:51.925] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:45:52.302] INFO: Expecting 41600 events.
[09:45:56.496] INFO: 41600 events read in total (3479ms).
[09:45:56.498] INFO: Test took 4573ms.
[09:45:56.509] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:45:56.509] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[09:45:56.509] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[09:45:56.878] INFO: PixTestAlive::addressDecodingTest() done
[09:45:56.878] 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:45:56.878] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[09:46:00.858] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[09:46:00.858] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[09:46:00.858] INFO: ----------------------------------------------------------------------
[09:46:00.859] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[09:46:00.859] INFO: ----------------------------------------------------------------------
[09:46:00.998] INFO: Expecting 768 events.
[09:46:02.126] INFO: 768 events read in total (414ms).
[09:46:02.127] INFO: Test took 1263ms.
[09:46:02.133] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:46:02.930] INFO: Expecting 41600 events.
[09:46:06.354] INFO: 41600 events read in total (2897ms).
[09:46:06.359] INFO: Test took 4226ms.
[09:46:06.473] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:46:06.474] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 662594
[09:46:06.474] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[09:46:06.474] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:46:06.524] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:46:07.189] INFO: Expecting 41600 events.
[09:46:10.722] INFO: 41600 events read in total (3006ms).
[09:46:10.726] INFO: Test took 4202ms.
[09:46:10.841] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:46:10.841] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 668458
[09:46:10.841] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[09:46:10.842] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:46:10.893] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:46:11.558] INFO: Expecting 41600 events.
[09:46:15.124] INFO: 41600 events read in total (3039ms).
[09:46:15.129] INFO: Test took 4236ms.
[09:46:15.242] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:46:15.242] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 671860
[09:46:15.242] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[09:46:15.242] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:46:15.295] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:46:15.960] INFO: Expecting 41600 events.
[09:46:19.518] INFO: 41600 events read in total (3032ms).
[09:46:19.523] INFO: Test took 4228ms.
[09:46:19.638] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:46:19.638] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 671215
[09:46:19.638] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[09:46:19.638] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:46:19.690] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:46:20.356] INFO: Expecting 41600 events.
[09:46:23.887] INFO: 41600 events read in total (3005ms).
[09:46:23.892] INFO: Test took 4202ms.
[09:46:24.010] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:46:24.010] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 670891
[09:46:24.010] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[09:46:24.010] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:46:24.068] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:46:24.737] INFO: Expecting 41600 events.
[09:46:28.328] INFO: 41600 events read in total (3064ms).
[09:46:28.332] INFO: Test took 4264ms.
[09:46:28.448] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:46:28.448] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 671499
[09:46:28.448] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[09:46:28.448] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:46:28.502] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:46:29.169] INFO: Expecting 41600 events.
[09:46:32.722] INFO: 41600 events read in total (3026ms).
[09:46:32.727] INFO: Test took 4225ms.
[09:46:32.839] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:46:32.840] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 672624
[09:46:32.840] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[09:46:32.840] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:46:32.893] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:46:33.558] INFO: Expecting 41600 events.
[09:46:37.151] INFO: 41600 events read in total (3067ms).
[09:46:37.156] INFO: Test took 4263ms.
[09:46:37.270] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:46:37.270] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 671693
[09:46:37.270] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[09:46:37.270] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:46:37.323] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:46:37.988] INFO: Expecting 41600 events.
[09:46:41.582] INFO: 41600 events read in total (3068ms).
[09:46:41.587] INFO: Test took 4264ms.
[09:46:41.701] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:46:41.701] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 671541
[09:46:41.701] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[09:46:41.701] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:46:41.754] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:46:42.419] INFO: Expecting 41600 events.
[09:46:46.039] INFO: 41600 events read in total (3093ms).
[09:46:46.043] INFO: Test took 4289ms.
[09:46:46.156] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:46:46.156] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 672163
[09:46:46.156] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[09:46:46.157] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:46:46.212] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:46:46.884] INFO: Expecting 41600 events.
[09:46:50.463] INFO: 41600 events read in total (3053ms).
[09:46:50.468] INFO: Test took 4256ms.
[09:46:50.586] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:46:50.586] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 670952
[09:46:50.586] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[09:46:50.586] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:46:50.640] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:46:51.307] INFO: Expecting 41600 events.
[09:46:54.918] INFO: 41600 events read in total (3085ms).
[09:46:54.923] INFO: Test took 4283ms.
[09:46:55.036] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:46:55.036] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 672672
[09:46:55.036] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[09:46:55.037] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:46:55.089] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:46:55.754] INFO: Expecting 41600 events.
[09:46:59.346] INFO: 41600 events read in total (3065ms).
[09:46:59.351] INFO: Test took 4262ms.
[09:46:59.461] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:46:59.462] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 673958
[09:46:59.462] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[09:46:59.462] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:46:59.517] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:47:00.185] INFO: Expecting 41600 events.
[09:47:03.774] INFO: 41600 events read in total (3063ms).
[09:47:03.778] INFO: Test took 4261ms.
[09:47:03.889] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:47:03.890] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 670295
[09:47:03.890] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[09:47:03.890] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:47:03.943] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:47:04.608] INFO: Expecting 41600 events.
[09:47:08.219] INFO: 41600 events read in total (3084ms).
[09:47:08.225] INFO: Test took 4282ms.
[09:47:08.353] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:47:08.353] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 671336
[09:47:08.354] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[09:47:08.354] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:47:08.408] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:47:09.074] INFO: Expecting 41600 events.
[09:47:12.653] INFO: 41600 events read in total (3053ms).
[09:47:12.658] INFO: Test took 4250ms.
[09:47:12.770] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:47:12.770] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 672386
[09:47:12.770] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[09:47:12.770] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:47:12.823] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:47:13.489] INFO: Expecting 41600 events.
[09:47:17.080] INFO: 41600 events read in total (3064ms).
[09:47:17.085] INFO: Test took 4262ms.
[09:47:17.198] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:47:17.198] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 672040
[09:47:17.198] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[09:47:17.198] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:47:17.252] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:47:17.917] INFO: Expecting 41600 events.
[09:47:21.515] INFO: 41600 events read in total (3071ms).
[09:47:21.519] INFO: Test took 4267ms.
[09:47:21.656] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:47:21.656] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 669722
[09:47:21.656] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[09:47:21.657] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:47:21.709] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:47:22.374] INFO: Expecting 41600 events.
[09:47:25.950] INFO: 41600 events read in total (3049ms).
[09:47:25.954] INFO: Test took 4245ms.
[09:47:26.067] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:47:26.067] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 672093
[09:47:26.067] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[09:47:26.068] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:47:26.121] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:47:26.786] INFO: Expecting 41600 events.
[09:47:30.233] INFO: 41600 events read in total (2921ms).
[09:47:30.238] INFO: Test took 4117ms.
[09:47:30.347] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:47:30.347] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 664758
[09:47:30.347] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[09:47:30.347] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:47:30.620] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 173 eff = 0.999255
[09:47:30.621] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 172 eff = 0.999591
[09:47:30.621] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 181 eff = 0.998798
[09:47:30.621] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 160 eff = 0.998149
[09:47:30.621] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 182 eff = 0.998053
[09:47:30.622] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 164 eff = 0.998101
[09:47:30.622] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 173 eff = 0.997909
[09:47:30.622] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 175 eff = 0.998438
[09:47:30.622] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 153 eff = 0.997644
[09:47:30.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 163 eff = 0.998149
[09:47:30.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 174 eff = 0.997909
[09:47:30.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 158 eff = 0.997885
[09:47:30.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 170 eff = 0.998245
[09:47:30.624] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 160 eff = 0.999063
[09:47:30.624] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 157 eff = 0.999423
[09:47:30.624] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 159 eff = 0.999351
[09:47:45.490] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[09:47:45.490] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[09:47:45.490] INFO: ----------------------------------------------------------------------
[09:47:45.491] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:47:45.491] INFO: ----------------------------------------------------------------------
[09:47:45.491] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[09:47:45.491] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[09:47:45.491] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[09:47:45.491] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[09:47:45.491] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[09:47:45.491] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[09:47:45.491] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[09:47:45.491] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[09:47:45.491] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[09:47:45.491] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[09:47:45.491] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[09:47:45.491] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[09:47:45.509] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:47:46.178] INFO: Expecting 208000 events.
[09:48:00.000] INFO: 208000 events read in total (14295ms).
[09:48:01.016] INFO: Test took 15507ms.
[09:48:01.598] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:48:01.598] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3067553
[09:48:01.598] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[09:48:01.598] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:48:02.032] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:48:02.032] INFO: number of red-efficiency pixels: 161 109 264 481 415 431 468 411 541 417 456 409 409 234 140 151
[09:48:02.032] INFO: number of X-ray hits detected: 115235 86308 146281 219572 234638 234136 232917 219509 240659 238048 240171 220844 220571 147328 92356 112420
[09:48:02.032] 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:48:02.032] INFO: number of Vcal hits detected: 207835 207884 207724 207470 207556 207529 207489 207556 207412 207552 207487 207562 207566 207755 207851 207840
[09:48:02.032] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[09:48:02.032] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.7 99.8 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[09:48:02.032] INFO: X-ray hit rate [MHz/cm2]: 33.8 25.3 42.9 64.4 68.8 68.6 68.3 64.3 70.5 69.8 70.4 64.7 64.7 43.2 27.1 33.0
[09:48:02.032] INFO: PixTestHighRate::doXPixelAlive() done
[09:48:02.077] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[09:48:02.077] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[09:48:02.077] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[09:48:02.077] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[09:48:02.077] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[09:48:02.077] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[09:48:02.077] INFO: PixTest:: pg_setup set to default.
[09:48:05.146] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[09:48:05.146] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[09:48:07.090] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[09:48:07.091] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 379.4mA
[09:48:07.092] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 465.5mA
[09:48:07.092] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[09:48:07.092] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[09:48:07.092] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[09:48:07.092] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[09:48:07.092] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[09:48:07.092] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[09:48:07.092] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[09:48:07.092] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[09:48:07.093] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[09:48:07.093] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[09:48:07.093] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[09:48:07.185] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[09:48:07.185] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[09:48:07.194] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[09:48:07.195] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[09:48:07.195] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[09:48:07.195] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[09:48:07.195] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[09:48:07.195] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[09:48:07.197] QUIET: Connection to board 43 closed.
[09:48:07.277] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[09:42:48.625] INFO: *** Welcome to pxar ***
[09:42:48.625] INFO: *** Today: 2016/06/14
[09:42:48.653] INFO: *** Version: v1.9.0-814-g7497
[09:42:48.653] INFO: readRocDacs: data/mp217/dacParameters35_C0.dat .. data/mp217/dacParameters35_C15.dat
[09:42:48.654] INFO: readTbmDacs: data/mp217/tbmParameters_C0a.dat .. data/mp217/tbmParameters_C0b.dat
[09:42:48.655] INFO: readMaskFile: data/mp217/defaultMaskFile.dat
[09:42:48.655] INFO: readTrimFile: data/mp217/trimParameters35_C0.dat .. data/mp217/trimParameters35_C15.dat
[09:42:48.803] INFO: clk: 4
[09:42:48.803] INFO: ctr: 4
[09:42:48.803] INFO: sda: 19
[09:42:48.803] INFO: tin: 9
[09:42:48.803] INFO: level: 15
[09:42:48.803] INFO: triggerdelay: 0
[09:42:48.803] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[09:42:48.803] INFO: Log level: DEBUG
[09:42:48.814] QUIET: Connection to board DTB_WRMNMR opened.
[09:42:48.817] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[09:42:48.820] INFO: RPC call hashes of host and DTB match: 398089610
[09:42:50.420] INFO: DUT info:
[09:42:50.420] INFO: The DUT currently contains the following objects:
[09:42:50.420] INFO: 2 TBM Cores tbm08c (2 ON)
[09:42:50.420] INFO: TBM Core alpha (0): 7 registers set
[09:42:50.420] INFO: TBM Core beta (1): 7 registers set
[09:42:50.420] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:42:50.420] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.420] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.420] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.420] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.420] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.420] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.420] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.420] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.420] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.420] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.420] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.420] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.420] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.420] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.420] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.420] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[09:42:50.421] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[09:42:50.422] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[09:42:50.423] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[09:42:50.424] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[09:42:50.426] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33193984
[09:42:50.426] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x272fbd0
[09:42:50.426] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x24d1e60
[09:42:50.426] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f8a99d94010
[09:42:50.426] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f8a9ffff510
[09:42:50.426] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33202176 fPxarMemory = 0x7f8a99d94010
[09:42:50.427] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 377mA
[09:42:50.429] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 463.9mA
[09:42:50.429] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[09:42:50.925] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2b1a7f0
[09:42:50.987] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[09:42:50.987] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[09:42:50.987] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[09:42:50.992] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[09:42:50.993] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[09:42:50.993] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[09:42:51.005] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[09:42:51.005] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[09:42:51.027] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[09:42:51.028] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[09:42:51.043] INFO: PixTestCmd::init()
[09:42:51.047] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[09:42:51.047] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[09:42:51.047] INFO: readGainPedestalParameters data/mp217/phCalibrationFitErr35_C0.dat .. data/mp217/phCalibrationFitErr35_C15.dat
[09:42:51.297] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[09:42:51.297] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[09:42:51.311] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[09:42:51.317] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[09:42:51.317] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[09:42:51.317] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[09:42:51.340] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[09:42:51.340] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[09:42:51.340] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[09:42:51.340] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[09:42:51.340] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[09:42:51.347] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[09:42:51.347] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[09:42:51.361] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[09:42:51.361] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[09:42:51.361] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[09:42:51.361] INFO: readReadbackCal: data/mp217/readbackCal_C0.dat .. data/mp217/readbackCal_C15.dat
[09:42:51.371] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[09:42:51.379] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[09:42:51.379] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[09:42:51.379] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[09:42:51.379] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[09:42:51.396] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[09:42:51.396] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[09:42:55.013] INFO: ######################################################################
[09:42:55.014] INFO: PixTestAlive::doTest()
[09:42:55.014] INFO: ######################################################################
[09:42:55.017] INFO: ----------------------------------------------------------------------
[09:42:55.017] 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:42:55.017] INFO: ----------------------------------------------------------------------
[09:42:55.019] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:42:55.399] INFO: Expecting 41600 events.
[09:42:59.593] INFO: 41600 events read in total (3476ms).
[09:42:59.740] INFO: Test took 4721ms.
[09:42:59.751] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:42:59.751] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[09:42:59.751] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[09:43:00.024] INFO: PixTestAlive::aliveTest() done
[09:43:00.024] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:43:00.025] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 44 39 58 73 74 61 72 69 92 76 93 69 68 45 27 34
[09:43:00.049] INFO: ----------------------------------------------------------------------
[09:43:00.049] 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:43:00.049] INFO: ----------------------------------------------------------------------
[09:43:00.051] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:43:00.430] INFO: Expecting 41600 events.
[09:43:03.340] INFO: 41600 events read in total (2195ms).
[09:43:03.341] INFO: Test took 3290ms.
[09:43:03.341] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:03.341] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[09:43:03.341] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[09:43:03.341] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:43:03.763] INFO: PixTestAlive::maskTest() done
[09:43:03.763] 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:43:03.783] INFO: ----------------------------------------------------------------------
[09:43:03.783] 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:43:03.783] INFO: ----------------------------------------------------------------------
[09:43:03.785] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:43:04.162] INFO: Expecting 41600 events.
[09:43:08.401] INFO: 41600 events read in total (3524ms).
[09:43:08.403] INFO: Test took 4618ms.
[09:43:08.414] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:08.414] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[09:43:08.414] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[09:43:08.783] INFO: PixTestAlive::addressDecodingTest() done
[09:43:08.783] 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:43:08.784] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[09:43:10.450] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[09:43:10.450] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[09:43:10.450] INFO: ----------------------------------------------------------------------
[09:43:10.450] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[09:43:10.450] INFO: ----------------------------------------------------------------------
[09:43:10.590] INFO: Expecting 768 events.
[09:43:11.718] INFO: 768 events read in total (413ms).
[09:43:11.719] INFO: Test took 1263ms.
[09:43:11.724] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:43:12.522] INFO: Expecting 41600 events.
[09:43:16.229] INFO: 41600 events read in total (3181ms).
[09:43:16.234] INFO: Test took 4509ms.
[09:43:16.387] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:16.387] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 889244
[09:43:16.387] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[09:43:16.387] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:43:16.451] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:43:17.116] INFO: Expecting 41600 events.
[09:43:20.890] INFO: 41600 events read in total (3247ms).
[09:43:20.895] INFO: Test took 4444ms.
[09:43:21.040] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:21.040] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 897968
[09:43:21.040] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[09:43:21.040] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:43:21.108] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:43:21.773] INFO: Expecting 41600 events.
[09:43:25.629] INFO: 41600 events read in total (3330ms).
[09:43:25.634] INFO: Test took 4526ms.
[09:43:25.780] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:25.780] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 899572
[09:43:25.780] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[09:43:25.780] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:43:25.848] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:43:26.513] INFO: Expecting 41600 events.
[09:43:30.340] INFO: 41600 events read in total (3300ms).
[09:43:30.345] INFO: Test took 4497ms.
[09:43:30.491] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:30.491] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 898689
[09:43:30.491] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[09:43:30.491] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:43:30.560] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:43:31.225] INFO: Expecting 41600 events.
[09:43:35.042] INFO: 41600 events read in total (3291ms).
[09:43:35.047] INFO: Test took 4487ms.
[09:43:35.194] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:35.194] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 899475
[09:43:35.194] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[09:43:35.194] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:43:35.263] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:43:35.928] INFO: Expecting 41600 events.
[09:43:39.796] INFO: 41600 events read in total (3342ms).
[09:43:39.801] INFO: Test took 4539ms.
[09:43:39.947] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:39.947] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 898761
[09:43:39.947] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[09:43:39.948] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:43:40.015] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:43:40.680] INFO: Expecting 41600 events.
[09:43:44.564] INFO: 41600 events read in total (3357ms).
[09:43:44.569] INFO: Test took 4554ms.
[09:43:44.717] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:44.717] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 899304
[09:43:44.717] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[09:43:44.717] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:43:44.785] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:43:45.450] INFO: Expecting 41600 events.
[09:43:49.331] INFO: 41600 events read in total (3354ms).
[09:43:49.337] INFO: Test took 4552ms.
[09:43:49.485] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:49.485] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 899128
[09:43:49.485] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[09:43:49.485] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:43:49.555] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:43:50.221] INFO: Expecting 41600 events.
[09:43:54.098] INFO: 41600 events read in total (3350ms).
[09:43:54.103] INFO: Test took 4548ms.
[09:43:54.250] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:54.250] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 900076
[09:43:54.250] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[09:43:54.250] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:43:54.319] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:43:54.984] INFO: Expecting 41600 events.
[09:43:58.870] INFO: 41600 events read in total (3359ms).
[09:43:58.875] INFO: Test took 4556ms.
[09:43:59.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:43:59.022] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 900371
[09:43:59.022] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[09:43:59.022] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:43:59.090] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:43:59.755] INFO: Expecting 41600 events.
[09:44:03.619] INFO: 41600 events read in total (3337ms).
[09:44:03.624] INFO: Test took 4534ms.
[09:44:03.769] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:03.770] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 898797
[09:44:03.770] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[09:44:03.770] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:44:03.838] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:44:04.503] INFO: Expecting 41600 events.
[09:44:08.399] INFO: 41600 events read in total (3369ms).
[09:44:08.404] INFO: Test took 4566ms.
[09:44:08.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:08.552] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 899616
[09:44:08.552] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[09:44:08.553] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:44:08.622] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:44:09.287] INFO: Expecting 41600 events.
[09:44:13.133] INFO: 41600 events read in total (3319ms).
[09:44:13.139] INFO: Test took 4517ms.
[09:44:13.286] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:13.286] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 899501
[09:44:13.286] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[09:44:13.286] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:44:13.355] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:44:14.020] INFO: Expecting 41600 events.
[09:44:17.907] INFO: 41600 events read in total (3360ms).
[09:44:17.913] INFO: Test took 4558ms.
[09:44:18.060] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:18.060] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 899598
[09:44:18.060] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[09:44:18.060] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:44:18.128] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:44:18.793] INFO: Expecting 41600 events.
[09:44:22.644] INFO: 41600 events read in total (3324ms).
[09:44:22.650] INFO: Test took 4522ms.
[09:44:22.797] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:22.797] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 898761
[09:44:22.797] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[09:44:22.798] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:44:22.866] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:44:23.531] INFO: Expecting 41600 events.
[09:44:27.406] INFO: 41600 events read in total (3349ms).
[09:44:27.411] INFO: Test took 4545ms.
[09:44:27.559] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:27.559] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 899544
[09:44:27.559] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[09:44:27.559] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:44:27.627] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:44:28.293] INFO: Expecting 41600 events.
[09:44:32.171] INFO: 41600 events read in total (3351ms).
[09:44:32.176] INFO: Test took 4548ms.
[09:44:32.323] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:32.323] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 898884
[09:44:32.323] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[09:44:32.323] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:44:32.392] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:44:33.056] INFO: Expecting 41600 events.
[09:44:36.933] INFO: 41600 events read in total (3350ms).
[09:44:36.938] INFO: Test took 4546ms.
[09:44:37.084] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:37.084] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 901026
[09:44:37.084] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[09:44:37.084] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:44:37.153] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:44:37.819] INFO: Expecting 41600 events.
[09:44:41.679] INFO: 41600 events read in total (3335ms).
[09:44:41.685] INFO: Test took 4532ms.
[09:44:41.832] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:41.832] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 897275
[09:44:41.832] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[09:44:41.832] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:44:41.901] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:44:42.566] INFO: Expecting 41600 events.
[09:44:46.293] INFO: 41600 events read in total (3200ms).
[09:44:46.299] INFO: Test took 4398ms.
[09:44:46.443] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:44:46.443] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 894392
[09:44:46.443] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[09:44:46.444] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:44:46.733] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 177 eff = 0.998894
[09:44:46.733] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 174 eff = 0.999351
[09:44:46.734] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 180 eff = 0.997572
[09:44:46.734] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 172 eff = 0.996322
[09:44:46.734] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 183 eff = 0.996394
[09:44:46.735] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 164 eff = 0.996226
[09:44:46.735] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 170 eff = 0.995601
[09:44:46.735] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 175 eff = 0.996394
[09:44:46.735] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 159 eff = 0.99613
[09:44:46.735] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 163 eff = 0.995505
[09:44:46.736] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 170 eff = 0.995817
[09:44:46.736] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 158 eff = 0.995913
[09:44:46.736] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 167 eff = 0.995889
[09:44:46.736] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 161 eff = 0.998149
[09:44:46.737] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 166 eff = 0.999135
[09:44:46.737] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 159 eff = 0.999087
[09:45:02.082] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[09:45:02.082] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[09:45:02.082] INFO: ----------------------------------------------------------------------
[09:45:02.082] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:45:02.082] INFO: ----------------------------------------------------------------------
[09:45:02.083] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[09:45:02.083] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[09:45:02.083] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[09:45:02.083] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[09:45:02.083] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[09:45:02.083] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[09:45:02.083] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[09:45:02.083] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[09:45:02.083] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[09:45:02.083] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[09:45:02.083] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[09:45:02.083] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[09:45:02.101] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[09:45:02.770] INFO: Expecting 208000 events.
[09:45:19.731] INFO: 208000 events read in total (16434ms).
[09:45:19.752] INFO: Test took 17651ms.
[09:45:20.464] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:45:20.464] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4186340
[09:45:20.464] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[09:45:20.464] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[09:45:20.974] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:45:20.974] INFO: number of red-efficiency pixels: 244 171 397 699 717 758 798 653 828 818 781 742 711 412 186 214
[09:45:20.974] INFO: number of X-ray hits detected: 158531 117889 200887 300652 321534 321377 321827 301935 329266 326598 329600 302378 303640 202320 127007 154339
[09:45:20.974] 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:45:20.974] INFO: number of Vcal hits detected: 207741 207818 207546 207207 207180 207141 207054 207256 207058 207050 207091 207137 207189 207529 207803 207776
[09:45:20.974] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.6 99.6 99.7 99.6 99.6 99.6 99.6 99.6 99.8 99.9 99.9
[09:45:20.974] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.6 99.6 99.6 99.5 99.6 99.5 99.5 99.6 99.6 99.6 99.8 99.9 99.9
[09:45:20.974] INFO: X-ray hit rate [MHz/cm2]: 46.5 34.6 58.9 88.1 94.2 94.2 94.3 88.5 96.5 95.7 96.6 88.6 89.0 59.3 37.2 45.2
[09:45:20.974] INFO: PixTestHighRate::doXPixelAlive() done
[09:45:21.019] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[09:45:21.019] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[09:45:21.019] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[09:45:21.019] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[09:45:21.019] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[09:45:21.019] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[09:45:21.019] INFO: PixTest:: pg_setup set to default.
[09:45:25.482] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[09:45:25.482] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[09:45:26.666] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[09:45:26.667] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 379.4mA
[09:45:26.668] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 465.5mA
[09:45:26.668] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[09:45:26.668] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[09:45:26.668] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[09:45:26.668] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[09:45:26.668] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[09:45:26.668] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[09:45:26.669] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[09:45:26.669] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[09:45:26.669] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[09:45:26.669] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[09:45:26.669] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[09:45:26.764] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[09:45:26.765] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[09:45:26.774] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[09:45:26.774] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[09:45:26.774] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[09:45:26.774] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[09:45:26.774] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[09:45:26.774] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[09:45:26.776] QUIET: Connection to board 43 closed.
[09:45:27.978] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master