Test Date: 1973-11-29 16:33
Analysis date: 2016-06-15 17:25
Logfile
hrData_40.log
[10:21:57.589] INFO: *** Welcome to pxar ***
[10:21:57.589] INFO: *** Today: 2016/06/15
[10:21:57.617] INFO: *** Version: v1.9.0-814-g7497
[10:21:57.617] INFO: readRocDacs: data/mk347/dacParameters35_C0.dat .. data/mk347/dacParameters35_C15.dat
[10:21:57.618] INFO: readTbmDacs: data/mk347/tbmParameters_C0a.dat .. data/mk347/tbmParameters_C0b.dat
[10:21:57.618] INFO: readMaskFile: data/mk347/defaultMaskFile.dat
[10:21:57.618] INFO: readTrimFile: data/mk347/trimParameters35_C0.dat .. data/mk347/trimParameters35_C15.dat
[10:21:57.766] INFO: clk: 4
[10:21:57.766] INFO: ctr: 4
[10:21:57.766] INFO: sda: 19
[10:21:57.766] INFO: tin: 9
[10:21:57.766] INFO: level: 15
[10:21:57.766] INFO: triggerdelay: 0
[10:21:57.766] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:21:57.766] INFO: Log level: DEBUG
[10:21:57.778] QUIET: Connection to board DTB_WRMNMR opened.
[10:21:57.781] 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)
------------------------------------------------------
[10:21:57.784] INFO: RPC call hashes of host and DTB match: 398089610
[10:21:59.387] INFO: DUT info:
[10:21:59.387] INFO: The DUT currently contains the following objects:
[10:21:59.387] INFO: 2 TBM Cores tbm08c (2 ON)
[10:21:59.388] INFO: TBM Core alpha (0): 7 registers set
[10:21:59.388] INFO: TBM Core beta (1): 7 registers set
[10:21:59.388] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:21:59.388] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:21:59.388] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[10:21:59.388] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:21:59.388] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:21:59.388] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:21:59.389] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:21:59.390] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[10:21:59.391] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[10:21:59.392] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[10:21:59.392] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:21:59.392] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:21:59.394] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35340288
[10:21:59.394] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xc8abd0
[10:21:59.394] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xa2ce60
[10:21:59.394] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f961dd94010
[10:21:59.394] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f9623fff510
[10:21:59.394] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35348480 fPxarMemory = 0x7f961dd94010
[10:21:59.395] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.1mA
[10:21:59.396] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[10:21:59.396] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[10:21:59.893] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x10758b0
[10:21:59.952] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[10:21:59.953] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[10:21:59.953] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[10:21:59.958] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[10:21:59.958] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[10:21:59.959] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[10:21:59.970] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[10:21:59.970] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[10:21:59.981] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[10:21:59.981] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[10:21:59.995] INFO: PixTestCmd::init()
[10:22:00.003] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[10:22:00.004] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[10:22:00.004] INFO: readGainPedestalParameters data/mk347/phCalibrationFitErr35_C0.dat .. data/mk347/phCalibrationFitErr35_C15.dat
[10:22:00.260] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[10:22:00.260] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[10:22:00.274] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[10:22:00.281] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[10:22:00.281] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[10:22:00.281] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[10:22:00.304] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[10:22:00.304] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[10:22:00.304] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[10:22:00.304] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[10:22:00.304] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[10:22:00.311] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[10:22:00.311] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[10:22:00.324] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[10:22:00.324] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[10:22:00.324] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[10:22:00.324] INFO: readReadbackCal: data/mk347/readbackCal_C0.dat .. data/mk347/readbackCal_C15.dat
[10:22:00.335] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[10:22:00.343] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[10:22:00.343] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[10:22:00.343] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[10:22:00.343] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[10:22:00.360] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[10:22:00.360] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[10:22:07.300] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[10:22:09.940] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[10:22:11.913] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[10:22:11.914] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[10:22:11.914] INFO: ----------------------------------------------------------------------
[10:22:11.914] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[10:22:11.914] INFO: ----------------------------------------------------------------------
[10:22:12.887] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[10:22:25.847] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[10:22:25.851] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:22:53.223] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1276887 events.
[10:22:58.699] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1276887, pixels seen in all events: 4928958
[10:22:58.798] INFO: Resuming triggers.
[10:23:11.762] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[10:23:11.796] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:23:39.266] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1277180 events.
[10:23:44.708] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1277180, pixels seen in all events: 4924629
[10:23:44.800] INFO: Resuming triggers.
[10:23:57.759] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[10:23:57.790] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:24:25.042] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1276649 events.
[10:24:30.501] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1276649, pixels seen in all events: 4931571
[10:24:30.659] INFO: Resuming triggers.
[10:24:43.619] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[10:24:43.653] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:25:10.928] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1276774 events.
[10:25:16.382] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1276774, pixels seen in all events: 4929945
[10:25:16.548] INFO: Resuming triggers.
[10:25:24.780] INFO: data taking finished, elapsed time: 60 seconds.
[10:25:24.976] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:25:42.561] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 810944 events.
[10:25:45.741] DEBUG: <PixTestXray.cc/processData:L823> # events read: 810944, pixels seen in all events: 3129746
[10:25:45.855] INFO: PixTest:: pg_setup set to default.
[10:25:45.933] INFO: PixTestXray::doPhRun() done
[10:30:24.818] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:30:24.818] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 392.3mA
[10:30:24.819] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.1mA
[10:30:24.819] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[10:30:24.819] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:30:24.819] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:30:24.819] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:30:24.819] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:30:24.820] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:30:24.820] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:30:24.820] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:30:24.820] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:30:24.820] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:30:24.820] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:30:24.820] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:30:24.820] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:30:24.820] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:30:24.820] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:30:24.820] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:30:24.820] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:30:24.820] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:30:24.820] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:30:25.093] QUIET: Connection to board 43 closed.
[10:30:25.173] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[10:30:31.621] INFO: *** Welcome to pxar ***
[10:30:31.622] INFO: *** Today: 2016/06/15
[10:30:31.646] INFO: *** Version: v1.9.0-814-g7497
[10:30:31.646] INFO: readRocDacs: data/mk347/dacParameters35_C0.dat .. data/mk347/dacParameters35_C15.dat
[10:30:31.647] INFO: readTbmDacs: data/mk347/tbmParameters_C0a.dat .. data/mk347/tbmParameters_C0b.dat
[10:30:31.647] INFO: readMaskFile: data/mk347/defaultMaskFile.dat
[10:30:31.647] INFO: readTrimFile: data/mk347/trimParameters35_C0.dat .. data/mk347/trimParameters35_C15.dat
[10:30:31.797] INFO: clk: 4
[10:30:31.797] INFO: ctr: 4
[10:30:31.797] INFO: sda: 19
[10:30:31.797] INFO: tin: 9
[10:30:31.797] INFO: level: 15
[10:30:31.797] INFO: triggerdelay: 0
[10:30:31.797] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:30:31.797] INFO: Log level: DEBUG
[10:30:31.808] QUIET: Connection to board DTB_WRMNMR opened.
[10:30:31.811] 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)
------------------------------------------------------
[10:30:31.814] INFO: RPC call hashes of host and DTB match: 398089610
[10:30:33.415] INFO: DUT info:
[10:30:33.415] INFO: The DUT currently contains the following objects:
[10:30:33.415] INFO: 2 TBM Cores tbm08c (2 ON)
[10:30:33.415] INFO: TBM Core alpha (0): 7 registers set
[10:30:33.415] INFO: TBM Core beta (1): 7 registers set
[10:30:33.415] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:30:33.415] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.415] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.415] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.415] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.415] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.415] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.415] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.415] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.415] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.415] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.415] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.415] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.415] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.415] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.416] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.416] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:30:33.416] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[10:30:33.417] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:30:33.418] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[10:30:33.419] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[10:30:33.419] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[10:30:33.419] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:30:33.419] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[10:30:33.419] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[10:30:33.419] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[10:30:33.419] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[10:30:33.419] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:30:33.419] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:30:33.421] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31674368
[10:30:33.421] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1da5bd0
[10:30:33.421] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1b47e60
[10:30:33.421] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fa5c9d94010
[10:30:33.421] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fa5cffff510
[10:30:33.421] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31682560 fPxarMemory = 0x7fa5c9d94010
[10:30:33.422] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.1mA
[10:30:33.423] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[10:30:33.423] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[10:30:33.918] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2190850
[10:30:33.982] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[10:30:33.982] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[10:30:33.983] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[10:30:33.988] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[10:30:33.988] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[10:30:33.988] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[10:30:34.000] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[10:30:34.000] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[10:30:34.023] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[10:30:34.023] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[10:30:34.039] INFO: PixTestCmd::init()
[10:30:34.049] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[10:30:34.049] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[10:30:34.049] INFO: readGainPedestalParameters data/mk347/phCalibrationFitErr35_C0.dat .. data/mk347/phCalibrationFitErr35_C15.dat
[10:30:34.297] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[10:30:34.297] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[10:30:34.311] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[10:30:34.318] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[10:30:34.318] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[10:30:34.318] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[10:30:34.340] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[10:30:34.340] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[10:30:34.340] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[10:30:34.340] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[10:30:34.340] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[10:30:34.345] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[10:30:34.346] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[10:30:34.359] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[10:30:34.359] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[10:30:34.359] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[10:30:34.359] INFO: readReadbackCal: data/mk347/readbackCal_C0.dat .. data/mk347/readbackCal_C15.dat
[10:30:34.371] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[10:30:34.380] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[10:30:34.380] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[10:30:34.380] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[10:30:34.380] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[10:30:34.398] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[10:30:34.398] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[10:30:40.181] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[10:30:41.925] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[10:30:43.322] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[10:30:43.322] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[10:30:43.322] INFO: ----------------------------------------------------------------------
[10:30:43.322] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[10:30:43.322] INFO: ----------------------------------------------------------------------
[10:30:44.295] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[10:30:51.772] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[10:30:51.776] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:31:16.809] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 736773 events.
[10:31:29.047] DEBUG: <PixTestXray.cc/processData:L823> # events read: 736773, pixels seen in all events: 11411743
[10:31:29.285] INFO: Resuming triggers.
[10:31:36.759] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[10:31:36.794] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:32:01.764] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 736289 events.
[10:32:13.205] DEBUG: <PixTestXray.cc/processData:L823> # events read: 736289, pixels seen in all events: 11416297
[10:32:13.394] INFO: Resuming triggers.
[10:32:20.870] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[10:32:20.904] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:32:45.945] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 736463 events.
[10:32:57.399] DEBUG: <PixTestXray.cc/processData:L823> # events read: 736463, pixels seen in all events: 11414496
[10:32:57.583] INFO: Resuming triggers.
[10:33:05.057] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[10:33:05.125] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:33:30.297] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 736278 events.
[10:33:42.036] DEBUG: <PixTestXray.cc/processData:L823> # events read: 736278, pixels seen in all events: 11416592
[10:33:42.283] INFO: Resuming triggers.
[10:33:49.756] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[10:33:49.791] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:34:15.153] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 736203 events.
[10:34:26.730] DEBUG: <PixTestXray.cc/processData:L823> # events read: 736203, pixels seen in all events: 11417231
[10:34:26.977] INFO: Resuming triggers.
[10:34:34.450] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[10:34:34.482] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:34:59.547] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 736130 events.
[10:35:11.041] DEBUG: <PixTestXray.cc/processData:L823> # events read: 736130, pixels seen in all events: 11418628
[10:35:11.257] INFO: Resuming triggers.
[10:35:18.731] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[10:35:18.761] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:35:43.791] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 736279 events.
[10:35:55.322] DEBUG: <PixTestXray.cc/processData:L823> # events read: 736279, pixels seen in all events: 11417131
[10:35:55.539] INFO: Resuming triggers.
[10:36:03.015] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[10:36:03.049] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:36:28.242] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 736513 events.
[10:36:39.691] DEBUG: <PixTestXray.cc/processData:L823> # events read: 736513, pixels seen in all events: 11413474
[10:36:39.880] INFO: Resuming triggers.
[10:36:40.159] INFO: data taking finished, elapsed time: 60 seconds.
[10:36:40.355] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[10:36:41.474] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 27410 events.
[10:36:41.901] DEBUG: <PixTestXray.cc/processData:L823> # events read: 27410, pixels seen in all events: 425893
[10:36:41.938] INFO: PixTest:: pg_setup set to default.
[10:36:41.978] INFO: PixTestXray::doPhRun() done
[10:36:57.546] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:36:57.547] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 392.3mA
[10:36:57.547] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 469.5mA
[10:36:57.547] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[10:36:57.547] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:36:57.547] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:36:57.548] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:36:57.548] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:36:57.548] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:36:57.548] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:36:57.548] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:36:57.548] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:36:57.548] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:36:57.548] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:36:57.548] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:36:57.548] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:36:57.548] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:36:57.548] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:36:57.548] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:36:57.548] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:36:57.548] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:36:57.549] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:36:57.867] QUIET: Connection to board 43 closed.
[10:36:57.947] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[10:16:57.494] INFO: *** Welcome to pxar ***
[10:16:57.494] INFO: *** Today: 2016/06/15
[10:16:57.520] INFO: *** Version: v1.9.0-814-g7497
[10:16:57.520] INFO: readRocDacs: data/mk347/dacParameters35_C0.dat .. data/mk347/dacParameters35_C15.dat
[10:16:57.521] INFO: readTbmDacs: data/mk347/tbmParameters_C0a.dat .. data/mk347/tbmParameters_C0b.dat
[10:16:57.521] INFO: readMaskFile: data/mk347/defaultMaskFile.dat
[10:16:57.521] INFO: readTrimFile: data/mk347/trimParameters35_C0.dat .. data/mk347/trimParameters35_C15.dat
[10:16:57.671] INFO: clk: 4
[10:16:57.671] INFO: ctr: 4
[10:16:57.671] INFO: sda: 19
[10:16:57.671] INFO: tin: 9
[10:16:57.671] INFO: level: 15
[10:16:57.671] INFO: triggerdelay: 0
[10:16:57.671] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:16:57.671] INFO: Log level: DEBUG
[10:16:57.682] QUIET: Connection to board DTB_WRMNMR opened.
[10:16:57.686] 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)
------------------------------------------------------
[10:16:57.688] INFO: RPC call hashes of host and DTB match: 398089610
[10:16:59.291] INFO: DUT info:
[10:16:59.291] INFO: The DUT currently contains the following objects:
[10:16:59.291] INFO: 2 TBM Cores tbm08c (2 ON)
[10:16:59.291] INFO: TBM Core alpha (0): 7 registers set
[10:16:59.291] INFO: TBM Core beta (1): 7 registers set
[10:16:59.291] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:16:59.291] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.291] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.291] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.291] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.291] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.291] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.291] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.291] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.291] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.291] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.291] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.292] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.292] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.292] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.292] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.292] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[10:16:59.292] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:16:59.293] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:16:59.294] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:16:59.295] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:16:59.297] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34848768
[10:16:59.297] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x27afbd0
[10:16:59.297] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2551e60
[10:16:59.297] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f2a11d94010
[10:16:59.297] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f2a17fff510
[10:16:59.297] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34856960 fPxarMemory = 0x7f2a11d94010
[10:16:59.299] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.1mA
[10:16:59.300] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[10:16:59.300] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[10:16:59.797] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2b9a860
[10:16:59.860] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[10:16:59.860] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[10:16:59.860] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[10:16:59.866] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[10:16:59.866] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[10:16:59.866] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[10:16:59.877] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[10:16:59.877] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[10:16:59.903] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[10:16:59.903] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[10:16:59.913] INFO: PixTestCmd::init()
[10:16:59.917] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[10:16:59.917] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[10:16:59.917] INFO: readGainPedestalParameters data/mk347/phCalibrationFitErr35_C0.dat .. data/mk347/phCalibrationFitErr35_C15.dat
[10:17:00.162] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[10:17:00.162] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[10:17:00.177] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[10:17:00.182] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[10:17:00.183] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[10:17:00.183] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[10:17:00.202] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[10:17:00.202] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[10:17:00.202] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[10:17:00.202] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[10:17:00.202] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[10:17:00.209] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[10:17:00.209] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[10:17:00.224] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[10:17:00.224] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[10:17:00.225] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[10:17:00.225] INFO: readReadbackCal: data/mk347/readbackCal_C0.dat .. data/mk347/readbackCal_C15.dat
[10:17:00.235] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[10:17:00.245] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[10:17:00.245] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[10:17:00.245] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[10:17:00.245] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[10:17:00.263] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[10:17:00.263] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[10:17:04.613] INFO: ######################################################################
[10:17:04.613] INFO: PixTestAlive::doTest()
[10:17:04.613] INFO: ######################################################################
[10:17:04.616] INFO: ----------------------------------------------------------------------
[10:17:04.616] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:17:04.616] INFO: ----------------------------------------------------------------------
[10:17:04.618] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:17:04.998] INFO: Expecting 41600 events.
[10:17:09.205] INFO: 41600 events read in total (3489ms).
[10:17:09.353] INFO: Test took 4735ms.
[10:17:09.363] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:09.363] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[10:17:09.363] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[10:17:09.635] INFO: PixTestAlive::aliveTest() done
[10:17:09.635] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:17:09.635] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 20 13 25 44 36 42 43 28 47 31 37 42 36 23 14 25
[10:17:09.660] INFO: ----------------------------------------------------------------------
[10:17:09.660] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:17:09.660] INFO: ----------------------------------------------------------------------
[10:17:09.662] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:17:10.039] INFO: Expecting 41600 events.
[10:17:12.966] INFO: 41600 events read in total (2211ms).
[10:17:12.966] INFO: Test took 3304ms.
[10:17:12.966] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:12.966] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[10:17:12.966] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[10:17:12.967] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:17:13.389] INFO: PixTestAlive::maskTest() done
[10:17:13.389] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:17:13.413] INFO: ----------------------------------------------------------------------
[10:17:13.413] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:17:13.413] INFO: ----------------------------------------------------------------------
[10:17:13.415] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:17:13.792] INFO: Expecting 41600 events.
[10:17:17.992] INFO: 41600 events read in total (3485ms).
[10:17:17.994] INFO: Test took 4579ms.
[10:17:18.004] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:18.004] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[10:17:18.004] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[10:17:18.376] INFO: PixTestAlive::addressDecodingTest() done
[10:17:18.376] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:17:18.376] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[10:17:20.585] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[10:17:20.585] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[10:17:20.585] INFO: ----------------------------------------------------------------------
[10:17:20.586] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:17:20.586] INFO: ----------------------------------------------------------------------
[10:17:20.724] INFO: Expecting 768 events.
[10:17:21.853] INFO: 768 events read in total (413ms).
[10:17:21.853] INFO: Test took 1262ms.
[10:17:21.859] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:17:22.661] INFO: Expecting 41600 events.
[10:17:25.906] INFO: 41600 events read in total (2718ms).
[10:17:25.910] INFO: Test took 4051ms.
[10:17:25.996] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:25.996] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 492807
[10:17:25.996] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[10:17:25.996] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:17:26.035] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:17:26.702] INFO: Expecting 41600 events.
[10:17:30.052] INFO: 41600 events read in total (2824ms).
[10:17:30.056] INFO: Test took 4020ms.
[10:17:30.138] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:30.139] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 497907
[10:17:30.139] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[10:17:30.139] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:17:30.179] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:17:30.845] INFO: Expecting 41600 events.
[10:17:34.218] INFO: 41600 events read in total (2847ms).
[10:17:34.221] INFO: Test took 4041ms.
[10:17:34.304] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:34.304] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499755
[10:17:34.305] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[10:17:34.305] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:17:34.346] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:17:35.011] INFO: Expecting 41600 events.
[10:17:38.389] INFO: 41600 events read in total (2851ms).
[10:17:38.392] INFO: Test took 4046ms.
[10:17:38.475] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:38.475] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499730
[10:17:38.475] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[10:17:38.475] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:17:38.516] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:17:39.181] INFO: Expecting 41600 events.
[10:17:42.560] INFO: 41600 events read in total (2853ms).
[10:17:42.564] INFO: Test took 4048ms.
[10:17:42.647] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:42.647] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499636
[10:17:42.647] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[10:17:42.647] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:17:42.688] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:17:43.354] INFO: Expecting 41600 events.
[10:17:46.735] INFO: 41600 events read in total (2855ms).
[10:17:46.739] INFO: Test took 4050ms.
[10:17:46.820] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:46.820] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499578
[10:17:46.820] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[10:17:46.821] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:17:46.862] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:17:47.527] INFO: Expecting 41600 events.
[10:17:50.919] INFO: 41600 events read in total (2865ms).
[10:17:50.923] INFO: Test took 4061ms.
[10:17:51.005] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:51.005] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 500303
[10:17:51.005] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[10:17:51.005] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:17:51.046] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:17:51.711] INFO: Expecting 41600 events.
[10:17:55.108] INFO: 41600 events read in total (2870ms).
[10:17:55.111] INFO: Test took 4065ms.
[10:17:55.194] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:55.194] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499421
[10:17:55.194] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[10:17:55.194] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:17:55.235] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:17:55.901] INFO: Expecting 41600 events.
[10:17:59.303] INFO: 41600 events read in total (2876ms).
[10:17:59.307] INFO: Test took 4071ms.
[10:17:59.390] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:59.390] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 500148
[10:17:59.390] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[10:17:59.390] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:17:59.431] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:18:00.096] INFO: Expecting 41600 events.
[10:18:03.495] INFO: 41600 events read in total (2873ms).
[10:18:03.499] INFO: Test took 4068ms.
[10:18:03.580] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:03.580] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499939
[10:18:03.580] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[10:18:03.581] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:18:03.621] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:18:04.286] INFO: Expecting 41600 events.
[10:18:07.681] INFO: 41600 events read in total (2869ms).
[10:18:07.684] INFO: Test took 4063ms.
[10:18:07.767] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:07.767] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499033
[10:18:07.767] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[10:18:07.768] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:18:07.808] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:18:08.473] INFO: Expecting 41600 events.
[10:18:11.861] INFO: 41600 events read in total (2862ms).
[10:18:11.865] INFO: Test took 4057ms.
[10:18:11.946] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:11.947] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 498836
[10:18:11.947] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[10:18:11.947] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:18:11.988] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:18:12.653] INFO: Expecting 41600 events.
[10:18:16.047] INFO: 41600 events read in total (2868ms).
[10:18:16.050] INFO: Test took 4062ms.
[10:18:16.134] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:16.134] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 500148
[10:18:16.134] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[10:18:16.134] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:18:16.176] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:18:16.841] INFO: Expecting 41600 events.
[10:18:20.231] INFO: 41600 events read in total (2863ms).
[10:18:20.235] INFO: Test took 4059ms.
[10:18:20.318] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:20.318] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499780
[10:18:20.318] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[10:18:20.319] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:18:20.360] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:18:21.025] INFO: Expecting 41600 events.
[10:18:23.256] WARNING: Channel 0 ROC 2: Readback start marker after 4 readouts!

[10:18:23.256] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (9) != Token Chain Length (8)

[10:18:23.256] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[10:18:23.256] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a096 8000 40e8 405 2a60 445 2864 450 2688 40e8 450 2685 40e8 450 2681 4d1 2a2d 40e8 450 2665 40e8 450 2688 509 2a49 40e8 299 2644 448 282e 450 2681 60b 2267 40e8 450 266e 40e8 458 284a 450 268d 50b 2289 e002 c000

[10:18:23.256] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a090 80b1 40e8 29d 2864 450 266f 40e8 450 2686 40e8 25b 2880 450 2685 40e8 c0 2441 24a 244d 450 2662 614 2465 40e8 450 2682 40e8 285 264a 450 266a 40e8 460 2447 450 2682 40e8 2c9 2223 450 26a1 e002 c000

[10:18:23.256] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a091 80c0 40e8 450 2685 40e8 450 2689 40e8 11b 2a69 450 2689 40e8 450 2669 40e9 411 2641 450 2689 40e9 2d5 266a 450 266f 40e9 450 2682 40e9 450 26a3 e002 c000

[10:18:23.256] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a092 8000 40e8 450 266d 40e8 450 2685 40e9 450 2680 40e9 450 2668 69b 2065 40e8 59 2645 450 268f 40e8 2c2 2a4c 450 2682 608 2822 40e9 450 2686 40e9 450 2689 e002 c000

[10:18:23.256] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a093 8040 40e8 450 2680 40e9 d5 226f 40eb 40e8 452 2461 450 2682 40e9 450 2669 40e8 450 26a0 40e9 30a 2a4f 30b 2663 450 2688 40e8 450 2682 40e9 450 2687 549 2a47 54a 2245 e002 c000

[10:18:23.256] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a094 80b1 40e8 450 266e 40e8 450 268a 40e8 450 2682 60b 2867 40e8 450 2661 40e8 a 282f 450 268c 40e8 450 266f 40e8 450 266f 840 2627 40e8 450 2689 e002 c000

[10:18:23.256] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a095 80c0 40e8 450 2680 40e8 450 266f 40e8 44d 2a2f 450 2685 852 2a61 40e8 450 2669 40e8 450 2685 40e8 450 266e 40e8 99 2860 450 2687 40e8 450 26a1 e002 c000

[10:18:24.424] INFO: 41600 events read in total (2872ms).
[10:18:24.428] INFO: Test took 4068ms.
[10:18:24.509] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:24.509] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499746
[10:18:24.509] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[10:18:24.510] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:18:24.551] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:18:25.216] INFO: Expecting 41600 events.
[10:18:28.610] INFO: 41600 events read in total (2867ms).
[10:18:28.614] INFO: Test took 4063ms.
[10:18:28.695] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:28.695] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499343
[10:18:28.695] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[10:18:28.695] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:18:28.736] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:18:29.401] INFO: Expecting 41600 events.
[10:18:32.789] INFO: 41600 events read in total (2861ms).
[10:18:32.793] INFO: Test took 4057ms.
[10:18:32.874] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:32.874] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499024
[10:18:32.874] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[10:18:32.874] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:18:32.915] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:18:33.581] INFO: Expecting 41600 events.
[10:18:36.970] INFO: 41600 events read in total (2863ms).
[10:18:36.974] INFO: Test took 4059ms.
[10:18:37.057] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:37.057] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499551
[10:18:37.057] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[10:18:37.057] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:18:37.098] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:18:37.764] INFO: Expecting 41600 events.
[10:18:41.139] INFO: 41600 events read in total (2849ms).
[10:18:41.142] INFO: Test took 4044ms.
[10:18:41.225] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:41.225] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499348
[10:18:41.225] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[10:18:41.225] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:18:41.266] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:18:41.932] INFO: Expecting 41600 events.
[10:18:45.201] INFO: 41600 events read in total (2742ms).
[10:18:45.205] INFO: Test took 3938ms.
[10:18:45.286] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:18:45.286] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496619
[10:18:45.286] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[10:18:45.287] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:18:45.548] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 137 eff = 0.999591
[10:18:45.548] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 157 eff = 0.99976
[10:18:45.548] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 154 eff = 0.999375
[10:18:45.549] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 146 eff = 0.998774
[10:18:45.549] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 169 eff = 0.998822
[10:18:45.549] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 156 eff = 0.99887
[10:18:45.549] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 160 eff = 0.998894
[10:18:45.550] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 170 eff = 0.999038
[10:18:45.550] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 175 eff = 0.998678
[10:18:45.550] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 162 eff = 0.998798
[10:18:45.550] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 162 eff = 0.999038
[10:18:45.551] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 164 eff = 0.99875
[10:18:45.551] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 190 eff = 0.99899
[10:18:45.551] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 164 eff = 0.999399
[10:18:45.551] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 158 eff = 0.999688
[10:18:45.552] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 166 eff = 0.999639
[10:19:10.609] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[10:19:10.609] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[10:19:10.610] INFO: ----------------------------------------------------------------------
[10:19:10.610] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:19:10.610] INFO: ----------------------------------------------------------------------
[10:19:10.610] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[10:19:10.610] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[10:19:10.610] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[10:19:10.610] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[10:19:10.610] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[10:19:10.610] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[10:19:10.610] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[10:19:10.610] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[10:19:10.610] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[10:19:10.610] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[10:19:10.610] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[10:19:10.610] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[10:19:10.629] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:19:11.298] INFO: Expecting 208000 events.
[10:19:24.792] INFO: 208000 events read in total (12967ms).
[10:19:24.805] INFO: Test took 14176ms.
[10:19:25.204] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:25.204] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2217135
[10:19:25.204] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[10:19:25.204] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:19:25.584] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:19:25.584] INFO: number of red-efficiency pixels: 127 78 139 304 240 252 247 210 256 276 248 247 249 161 85 109
[10:19:25.584] INFO: number of X-ray hits detected: 83324 61881 106093 160984 168600 171906 169335 145900 161378 175460 172267 160711 162836 104907 64070 80924
[10:19:25.584] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:19:25.584] INFO: number of Vcal hits detected: 207869 207920 207857 207681 207751 207731 207742 207775 207687 207718 207741 207740 207743 207836 207914 207890
[10:19:25.584] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[10:19:25.584] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[10:19:25.584] INFO: X-ray hit rate [MHz/cm2]: 24.4 18.1 31.1 47.2 49.4 50.4 49.6 42.8 47.3 51.4 50.5 47.1 47.7 30.7 18.8 23.7
[10:19:25.584] INFO: PixTestHighRate::doXPixelAlive() done
[10:19:25.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[10:19:25.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[10:19:25.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[10:19:25.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[10:19:25.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[10:19:25.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[10:19:25.630] INFO: PixTest:: pg_setup set to default.
[10:19:28.769] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[10:19:28.769] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[10:19:30.898] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:19:30.898] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 392.3mA
[10:19:30.899] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[10:19:30.899] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[10:19:30.899] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:19:30.899] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:19:30.899] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:19:30.899] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:19:30.899] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:19:30.900] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:19:30.900] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:19:30.900] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:19:30.900] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:19:30.900] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:19:30.990] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:19:30.990] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:19:30.999] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:19:30.999] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:19:30.999] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:19:30.999] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:19:30.999] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:19:30.999] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:19:31.003] QUIET: Connection to board 43 closed.
[10:19:31.082] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[10:13:50.135] INFO: *** Welcome to pxar ***
[10:13:50.136] INFO: *** Today: 2016/06/15
[10:13:50.161] INFO: *** Version: v1.9.0-814-g7497
[10:13:50.161] INFO: readRocDacs: data/mk347/dacParameters35_C0.dat .. data/mk347/dacParameters35_C15.dat
[10:13:50.162] INFO: readTbmDacs: data/mk347/tbmParameters_C0a.dat .. data/mk347/tbmParameters_C0b.dat
[10:13:50.162] INFO: readMaskFile: data/mk347/defaultMaskFile.dat
[10:13:50.162] INFO: readTrimFile: data/mk347/trimParameters35_C0.dat .. data/mk347/trimParameters35_C15.dat
[10:13:50.313] INFO: clk: 4
[10:13:50.313] INFO: ctr: 4
[10:13:50.313] INFO: sda: 19
[10:13:50.313] INFO: tin: 9
[10:13:50.313] INFO: level: 15
[10:13:50.313] INFO: triggerdelay: 0
[10:13:50.313] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:13:50.313] INFO: Log level: DEBUG
[10:13:50.324] QUIET: Connection to board DTB_WRMNMR opened.
[10:13:50.328] 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)
------------------------------------------------------
[10:13:50.331] INFO: RPC call hashes of host and DTB match: 398089610
[10:13:51.933] INFO: DUT info:
[10:13:51.933] INFO: The DUT currently contains the following objects:
[10:13:51.933] INFO: 2 TBM Cores tbm08c (2 ON)
[10:13:51.933] INFO: TBM Core alpha (0): 7 registers set
[10:13:51.933] INFO: TBM Core beta (1): 7 registers set
[10:13:51.933] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:13:51.933] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.933] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.933] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.933] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.933] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.933] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.933] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.933] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.933] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.933] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.933] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.933] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.933] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.933] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.934] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.934] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:13:51.934] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[10:13:51.935] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:13:51.936] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:13:51.937] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:13:51.939] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34861056
[10:13:51.939] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1330bd0
[10:13:51.939] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x10d2e60
[10:13:51.939] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f6f1bf91010
[10:13:51.939] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f6f221fc510
[10:13:51.939] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34869248 fPxarMemory = 0x7f6f1bf91010
[10:13:51.940] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.1mA
[10:13:51.942] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[10:13:51.942] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[10:13:52.440] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x171b6d0
[10:13:52.507] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[10:13:52.507] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[10:13:52.507] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[10:13:52.513] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[10:13:52.513] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[10:13:52.513] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[10:13:52.525] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[10:13:52.525] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[10:13:52.543] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[10:13:52.543] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[10:13:52.552] INFO: PixTestCmd::init()
[10:13:52.567] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[10:13:52.567] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[10:13:52.567] INFO: readGainPedestalParameters data/mk347/phCalibrationFitErr35_C0.dat .. data/mk347/phCalibrationFitErr35_C15.dat
[10:13:52.815] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[10:13:52.815] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[10:13:52.830] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[10:13:52.836] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[10:13:52.836] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[10:13:52.836] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[10:13:52.857] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[10:13:52.857] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[10:13:52.857] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[10:13:52.857] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[10:13:52.857] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[10:13:52.863] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[10:13:52.864] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[10:13:52.879] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[10:13:52.879] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[10:13:52.879] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[10:13:52.879] INFO: readReadbackCal: data/mk347/readbackCal_C0.dat .. data/mk347/readbackCal_C15.dat
[10:13:52.889] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[10:13:52.898] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[10:13:52.898] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[10:13:52.898] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[10:13:52.898] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[10:13:52.916] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[10:13:52.916] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[10:13:57.861] INFO: ######################################################################
[10:13:57.861] INFO: PixTestAlive::doTest()
[10:13:57.861] INFO: ######################################################################
[10:13:57.864] INFO: ----------------------------------------------------------------------
[10:13:57.864] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:13:57.864] INFO: ----------------------------------------------------------------------
[10:13:57.867] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:13:58.244] INFO: Expecting 41600 events.
[10:14:02.458] INFO: 41600 events read in total (3496ms).
[10:14:02.604] INFO: Test took 4737ms.
[10:14:02.614] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:02.614] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[10:14:02.614] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[10:14:02.884] INFO: PixTestAlive::aliveTest() done
[10:14:02.884] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:14:02.884] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 34 20 37 63 59 67 64 41 69 66 71 57 68 49 26 57
[10:14:02.912] INFO: ----------------------------------------------------------------------
[10:14:02.912] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:14:02.912] INFO: ----------------------------------------------------------------------
[10:14:02.914] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:14:03.291] INFO: Expecting 41600 events.
[10:14:06.214] INFO: 41600 events read in total (2207ms).
[10:14:06.215] INFO: Test took 3301ms.
[10:14:06.215] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:06.215] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[10:14:06.215] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[10:14:06.215] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:14:06.636] INFO: PixTestAlive::maskTest() done
[10:14:06.636] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:14:06.658] INFO: ----------------------------------------------------------------------
[10:14:06.658] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:14:06.658] INFO: ----------------------------------------------------------------------
[10:14:06.660] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:14:07.038] INFO: Expecting 41600 events.
[10:14:11.242] INFO: 41600 events read in total (3488ms).
[10:14:11.243] INFO: Test took 4583ms.
[10:14:11.254] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:11.254] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[10:14:11.254] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[10:14:11.622] INFO: PixTestAlive::addressDecodingTest() done
[10:14:11.622] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:14:11.622] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[10:14:28.753] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[10:14:28.753] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[10:14:28.753] INFO: ----------------------------------------------------------------------
[10:14:28.754] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:14:28.754] INFO: ----------------------------------------------------------------------
[10:14:28.893] INFO: Expecting 768 events.
[10:14:30.021] INFO: 768 events read in total (413ms).
[10:14:30.022] INFO: Test took 1263ms.
[10:14:30.028] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:14:30.825] INFO: Expecting 41600 events.
[10:14:34.362] INFO: 41600 events read in total (3011ms).
[10:14:34.368] INFO: Test took 4340ms.
[10:14:34.496] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:34.496] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 742210
[10:14:34.496] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[10:14:34.496] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:14:34.551] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:14:35.216] INFO: Expecting 41600 events.
[10:14:38.851] INFO: 41600 events read in total (3108ms).
[10:14:38.855] INFO: Test took 4304ms.
[10:14:38.980] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:38.980] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 747898
[10:14:38.980] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[10:14:38.981] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:14:39.038] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:14:39.703] INFO: Expecting 41600 events.
[10:14:43.354] INFO: 41600 events read in total (3125ms).
[10:14:43.359] INFO: Test took 4321ms.
[10:14:43.486] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:43.486] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 749253
[10:14:43.486] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[10:14:43.486] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:14:43.544] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:14:44.209] INFO: Expecting 41600 events.
[10:14:47.869] INFO: 41600 events read in total (3133ms).
[10:14:47.874] INFO: Test took 4330ms.
[10:14:47.998] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:47.998] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 747674
[10:14:47.998] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[10:14:47.999] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:14:48.055] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:14:48.720] INFO: Expecting 41600 events.
[10:14:52.392] INFO: 41600 events read in total (3145ms).
[10:14:52.397] INFO: Test took 4342ms.
[10:14:52.521] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:52.521] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 750913
[10:14:52.521] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[10:14:52.521] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:14:52.578] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:14:53.244] INFO: Expecting 41600 events.
[10:14:56.925] INFO: 41600 events read in total (3154ms).
[10:14:56.929] INFO: Test took 4351ms.
[10:14:57.055] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:57.055] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 747945
[10:14:57.055] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[10:14:57.055] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:14:57.112] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:14:57.777] INFO: Expecting 41600 events.
[10:15:01.451] INFO: 41600 events read in total (3147ms).
[10:15:01.456] INFO: Test took 4344ms.
[10:15:01.580] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:01.580] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 749310
[10:15:01.580] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[10:15:01.581] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:15:01.638] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:15:02.303] INFO: Expecting 41600 events.
[10:15:05.987] INFO: 41600 events read in total (3157ms).
[10:15:05.992] INFO: Test took 4354ms.
[10:15:06.116] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:06.116] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 749621
[10:15:06.116] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[10:15:06.116] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:15:06.173] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:15:06.838] INFO: Expecting 41600 events.
[10:15:10.510] INFO: 41600 events read in total (3145ms).
[10:15:10.515] INFO: Test took 4342ms.
[10:15:10.640] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:10.640] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 748768
[10:15:10.640] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[10:15:10.640] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:15:10.697] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:15:11.362] INFO: Expecting 41600 events.
[10:15:15.042] INFO: 41600 events read in total (3154ms).
[10:15:15.047] INFO: Test took 4350ms.
[10:15:15.172] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:15.172] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 748630
[10:15:15.172] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[10:15:15.173] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:15:15.229] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:15:15.895] INFO: Expecting 41600 events.
[10:15:19.583] INFO: 41600 events read in total (3162ms).
[10:15:19.588] INFO: Test took 4359ms.
[10:15:19.712] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:19.713] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 749473
[10:15:19.713] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[10:15:19.713] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:15:19.770] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:15:20.435] INFO: Expecting 41600 events.
[10:15:24.128] INFO: 41600 events read in total (3166ms).
[10:15:24.133] INFO: Test took 4363ms.
[10:15:24.257] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:24.257] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 749434
[10:15:24.257] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[10:15:24.258] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:15:24.314] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:15:24.979] INFO: Expecting 41600 events.
[10:15:28.660] INFO: 41600 events read in total (3154ms).
[10:15:28.665] INFO: Test took 4351ms.
[10:15:28.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:28.793] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 750861
[10:15:28.793] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[10:15:28.793] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:15:28.850] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:15:29.515] INFO: Expecting 41600 events.
[10:15:33.208] INFO: 41600 events read in total (3166ms).
[10:15:33.213] INFO: Test took 4363ms.
[10:15:33.338] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:33.338] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 749442
[10:15:33.338] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[10:15:33.338] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:15:33.396] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:15:34.061] INFO: Expecting 41600 events.
[10:15:37.717] INFO: 41600 events read in total (3130ms).
[10:15:37.722] INFO: Test took 4326ms.
[10:15:37.846] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:37.846] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 750382
[10:15:37.846] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[10:15:37.846] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:15:37.904] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:15:38.568] INFO: Expecting 41600 events.
[10:15:42.261] INFO: 41600 events read in total (3166ms).
[10:15:42.266] INFO: Test took 4362ms.
[10:15:42.391] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:42.391] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 751051
[10:15:42.391] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[10:15:42.392] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:15:42.449] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:15:43.113] INFO: Expecting 41600 events.
[10:15:46.799] INFO: 41600 events read in total (3159ms).
[10:15:46.804] INFO: Test took 4355ms.
[10:15:46.929] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:46.929] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 750698
[10:15:46.929] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[10:15:46.929] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:15:46.987] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:15:47.652] INFO: Expecting 41600 events.
[10:15:51.322] INFO: 41600 events read in total (3144ms).
[10:15:51.327] INFO: Test took 4340ms.
[10:15:51.452] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:51.452] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 750669
[10:15:51.452] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[10:15:51.453] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:15:51.509] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:15:52.174] INFO: Expecting 41600 events.
[10:15:55.834] INFO: 41600 events read in total (3133ms).
[10:15:55.839] INFO: Test took 4330ms.
[10:15:55.962] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:15:55.962] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 748806
[10:15:55.962] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[10:15:55.963] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:15:56.020] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:15:56.685] INFO: Expecting 41600 events.
[10:16:00.215] INFO: 41600 events read in total (3003ms).
[10:16:00.219] INFO: Test took 4199ms.
[10:16:00.342] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:16:00.342] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 744854
[10:16:00.342] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[10:16:00.343] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:16:00.621] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 146 eff = 0.999327
[10:16:00.621] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 154 eff = 0.999447
[10:16:00.621] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 155 eff = 0.998365
[10:16:00.622] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 158 eff = 0.997332
[10:16:00.622] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 171 eff = 0.99762
[10:16:00.622] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 159 eff = 0.997043
[10:16:00.622] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 159 eff = 0.997067
[10:16:00.622] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 173 eff = 0.997572
[10:16:00.622] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 170 eff = 0.997332
[10:16:00.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 160 eff = 0.996827
[10:16:00.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 160 eff = 0.997236
[10:16:00.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 161 eff = 0.997091
[10:16:00.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 190 eff = 0.997692
[10:16:00.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 161 eff = 0.99863
[10:16:00.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 159 eff = 0.999375
[10:16:00.623] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 163 eff = 0.999207
[10:16:13.537] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[10:16:13.537] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[10:16:13.538] INFO: ----------------------------------------------------------------------
[10:16:13.538] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:16:13.538] INFO: ----------------------------------------------------------------------
[10:16:13.538] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[10:16:13.538] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[10:16:13.538] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[10:16:13.538] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[10:16:13.538] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[10:16:13.538] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[10:16:13.538] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[10:16:13.538] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[10:16:13.538] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[10:16:13.538] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[10:16:13.538] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[10:16:13.538] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[10:16:13.556] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:16:14.225] INFO: Expecting 208000 events.
[10:16:29.787] INFO: 208000 events read in total (15035ms).
[10:16:29.805] INFO: Test took 16249ms.
[10:16:30.400] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:16:30.400] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3446803
[10:16:30.400] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[10:16:30.401] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:16:30.856] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:16:30.856] INFO: number of red-efficiency pixels: 201 132 293 536 517 580 605 462 527 623 562 577 508 280 137 161
[10:16:30.856] INFO: number of X-ray hits detected: 130437 97728 166415 252165 265643 269722 266624 227782 254080 276414 270573 252791 255463 165505 101235 127667
[10:16:30.856] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:16:30.856] INFO: number of Vcal hits detected: 207793 207862 207690 207407 207443 207364 207355 207498 207383 207310 207375 207346 207446 207693 207858 207835
[10:16:30.856] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.7 99.8 99.7 99.7 99.8 99.7 99.7 99.7 99.7 99.8 99.9 99.9 99.9
[10:16:30.856] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.7 99.7 99.7 99.7 99.8 99.7 99.7 99.7 99.7 99.7 99.9 99.9 99.9
[10:16:30.856] INFO: X-ray hit rate [MHz/cm2]: 38.2 28.6 48.8 73.9 77.9 79.1 78.1 66.8 74.5 81.0 79.3 74.1 74.9 48.5 29.7 37.4
[10:16:30.856] INFO: PixTestHighRate::doXPixelAlive() done
[10:16:30.901] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[10:16:30.901] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[10:16:30.901] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[10:16:30.901] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[10:16:30.901] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[10:16:30.901] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[10:16:30.901] INFO: PixTest:: pg_setup set to default.
[10:16:46.313] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[10:16:46.313] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[10:16:49.881] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:16:49.882] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 392.3mA
[10:16:49.883] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[10:16:49.883] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[10:16:49.883] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:16:49.883] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:16:49.883] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:16:49.883] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:16:49.883] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:16:49.883] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:16:49.883] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:16:49.884] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:16:49.884] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:16:49.884] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:16:49.975] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:16:49.975] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:16:49.984] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:16:49.984] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:16:49.985] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:16:49.985] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:16:49.985] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:16:49.985] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:16:49.987] QUIET: Connection to board 43 closed.
[10:16:50.066] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[10:10:35.999] INFO: *** Welcome to pxar ***
[10:10:35.999] INFO: *** Today: 2016/06/15
[10:10:36.023] INFO: *** Version: v1.9.0-814-g7497
[10:10:36.023] INFO: readRocDacs: data/mk347/dacParameters35_C0.dat .. data/mk347/dacParameters35_C15.dat
[10:10:36.024] INFO: readTbmDacs: data/mk347/tbmParameters_C0a.dat .. data/mk347/tbmParameters_C0b.dat
[10:10:36.024] INFO: readMaskFile: data/mk347/defaultMaskFile.dat
[10:10:36.024] INFO: readTrimFile: data/mk347/trimParameters35_C0.dat .. data/mk347/trimParameters35_C15.dat
[10:10:36.178] INFO: clk: 4
[10:10:36.178] INFO: ctr: 4
[10:10:36.178] INFO: sda: 19
[10:10:36.178] INFO: tin: 9
[10:10:36.178] INFO: level: 15
[10:10:36.178] INFO: triggerdelay: 0
[10:10:36.178] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[10:10:36.178] INFO: Log level: DEBUG
[10:10:36.189] QUIET: Connection to board DTB_WRMNMR opened.
[10:10:36.192] 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)
------------------------------------------------------
[10:10:36.195] INFO: RPC call hashes of host and DTB match: 398089610
[10:10:37.798] INFO: DUT info:
[10:10:37.798] INFO: The DUT currently contains the following objects:
[10:10:37.798] INFO: 2 TBM Cores tbm08c (2 ON)
[10:10:37.798] INFO: TBM Core alpha (0): 7 registers set
[10:10:37.798] INFO: TBM Core beta (1): 7 registers set
[10:10:37.798] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:10:37.798] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.798] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.798] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.798] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.799] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.799] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.799] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.799] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.799] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.799] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.799] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.799] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.799] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.799] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.799] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.799] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:10:37.799] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[10:10:37.799] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:10:37.799] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:10:37.799] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:10:37.799] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:10:37.799] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[10:10:37.799] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:10:37.799] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[10:10:37.799] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[10:10:37.799] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:10:37.799] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:10:37.799] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[10:10:37.799] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:10:37.799] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:10:37.799] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:10:37.800] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[10:10:37.801] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[10:10:37.802] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[10:10:37.804] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32747520
[10:10:37.804] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1e2abd0
[10:10:37.805] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1bcce60
[10:10:37.805] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f8425d94010
[10:10:37.805] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f842bfff510
[10:10:37.805] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32755712 fPxarMemory = 0x7f8425d94010
[10:10:37.806] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.1mA
[10:10:37.807] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[10:10:37.807] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[10:10:38.302] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x22157f0
[10:10:38.362] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[10:10:38.363] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[10:10:38.363] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[10:10:38.368] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[10:10:38.368] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[10:10:38.368] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[10:10:38.381] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[10:10:38.381] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[10:10:38.399] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[10:10:38.399] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[10:10:38.418] INFO: PixTestCmd::init()
[10:10:38.430] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[10:10:38.431] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[10:10:38.431] INFO: readGainPedestalParameters data/mk347/phCalibrationFitErr35_C0.dat .. data/mk347/phCalibrationFitErr35_C15.dat
[10:10:38.676] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[10:10:38.676] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[10:10:38.690] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[10:10:38.696] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[10:10:38.696] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[10:10:38.696] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[10:10:38.716] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[10:10:38.716] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[10:10:38.716] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[10:10:38.716] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[10:10:38.716] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[10:10:38.722] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[10:10:38.722] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[10:10:38.739] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[10:10:38.739] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[10:10:38.739] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[10:10:38.739] INFO: readReadbackCal: data/mk347/readbackCal_C0.dat .. data/mk347/readbackCal_C15.dat
[10:10:38.750] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[10:10:38.757] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[10:10:38.757] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[10:10:38.757] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[10:10:38.757] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[10:10:38.774] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[10:10:38.774] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[10:10:46.533] INFO: ######################################################################
[10:10:46.533] INFO: PixTestAlive::doTest()
[10:10:46.533] INFO: ######################################################################
[10:10:46.536] INFO: ----------------------------------------------------------------------
[10:10:46.536] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:10:46.536] INFO: ----------------------------------------------------------------------
[10:10:46.539] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:10:46.918] INFO: Expecting 41600 events.
[10:10:51.125] INFO: 41600 events read in total (3488ms).
[10:10:51.272] INFO: Test took 4733ms.
[10:10:51.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:10:51.283] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[10:10:51.283] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[10:10:51.553] INFO: PixTestAlive::aliveTest() done
[10:10:51.553] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:10:51.553] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 47 30 67 79 77 92 85 80 104 95 76 104 92 45 38 49
[10:10:51.577] INFO: ----------------------------------------------------------------------
[10:10:51.577] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:10:51.577] INFO: ----------------------------------------------------------------------
[10:10:51.580] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:10:51.958] INFO: Expecting 41600 events.
[10:10:54.888] INFO: 41600 events read in total (2215ms).
[10:10:54.889] INFO: Test took 3309ms.
[10:10:54.889] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:10:54.889] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[10:10:54.889] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[10:10:54.889] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:10:55.311] INFO: PixTestAlive::maskTest() done
[10:10:55.311] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:10:55.334] INFO: ----------------------------------------------------------------------
[10:10:55.334] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:10:55.334] INFO: ----------------------------------------------------------------------
[10:10:55.336] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:10:55.713] INFO: Expecting 41600 events.
[10:10:59.893] INFO: 41600 events read in total (3465ms).
[10:10:59.894] INFO: Test took 4558ms.
[10:10:59.905] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:10:59.905] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[10:10:59.905] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[10:11:00.276] INFO: PixTestAlive::addressDecodingTest() done
[10:11:00.276] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:11:00.276] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[10:11:03.929] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[10:11:03.929] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[10:11:03.929] INFO: ----------------------------------------------------------------------
[10:11:03.929] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:11:03.929] INFO: ----------------------------------------------------------------------
[10:11:04.069] INFO: Expecting 768 events.
[10:11:05.198] INFO: 768 events read in total (413ms).
[10:11:05.198] INFO: Test took 1262ms.
[10:11:05.204] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:11:06.002] INFO: Expecting 41600 events.
[10:11:09.848] INFO: 41600 events read in total (3320ms).
[10:11:09.853] INFO: Test took 4648ms.
[10:11:10.017] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:10.017] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 965184
[10:11:10.017] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[10:11:10.018] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:11:10.091] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:11:10.755] INFO: Expecting 41600 events.
[10:11:14.665] INFO: 41600 events read in total (3383ms).
[10:11:14.670] INFO: Test took 4579ms.
[10:11:14.831] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:14.831] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 969598
[10:11:14.831] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[10:11:14.832] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:11:14.904] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:11:15.569] INFO: Expecting 41600 events.
[10:11:18.026] WARNING: Channel 0 ROC 2: Readback start marker after 4 readouts!

[10:11:18.026] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (9) != Token Chain Length (8)

[10:11:18.026] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[10:11:18.026] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a096 8000 40e8 450 266f 620 20c5 40e8 450 268c 40e8 40e8 421 264b 450 268a 711 2882 40e8 21d 2a69 450 268d 40e8 112 224f 450 266d 558 2a69 709 204c 81d 244f 40e8 20c 202f 292 2a46 450 26a5 850 264c 40e8 414 2240 414 2641 450 2689 55d 2461 54c 2862 e002 c000

[10:11:18.026] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a090 80b1 40e8 450 266e 651 2669 84b 2267 40e8 2da 2861 450 2682 40e8 40e8 290 2641 450 2682 40e8 450 26a2 40e8 a 2a45 249 2a2d 2c2 2648 450 2685 618 204b 618 222f 6cc 282c 40e8 b 2061 300 2a49 450 2685 40e8 450 268f 4e0 204d e002 c000

[10:11:18.026] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a091 80c0 40e8 450 266e 40e8 450 2685 40e8 450 266f 489 244d 488 284f 6d5 2a64 40e8 450 266d 85c 2262 40e9 450 266f 40e9 160 2641 360 2869 350 222d 450 266d 45a 2669 40e9 9b 2a6d 450 2684 4c4 26a5 74c 2a49 813 2440 40e9 450 26a3 e002 c000

[10:11:18.026] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a092 8000 40e8 450 2680 40e8 450 2689 40e9 450 268b 40e9 450 2682 753 2a69 40e8 450 2688 40e8 dc 282e 453 2447 450 2686 709 2064 40e9 a 284c 5b 226f 450 266f 40e9 450 26aa e002 c000

[10:11:18.026] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a093 8040 40e8 450 266c 40e9 40eb 40e8 450 2681 40e9 450 2684 50b 206a 503 2468 40e8 e2 2a4f 40e9 5 204f 450 266a 84d 266b 40e8 4e0 224f 6ca 224c 40e9 30c 264f 450 26a5 e002 c000

[10:11:18.026] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a094 80b1 40e8 450 2684 40e8 99 2660 450 268c 40e8 450 2685 650 2645 40e8 4d 204c 450 266f 751 2048 754 2261 40e8 222 2a6f 450 268f 40e8 14d 2046 445 244f 450 2686 515 226a 40e8 450 2684 842 2a45 40e8 35c 2440 450 26a6 e002 c000

[10:11:18.026] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a095 80c0 40e8 450 2680 40e8 450 2667 6dd 204f 6db 2663 6db 2a6e 40e8 450 2685 40e8 450 2665 40e8 450 2689 40e8 60 2642 41a 264f 450 2665 650 204e 40e8 450 266f 60a 282f 60a 2a2d 6d8 204d 40e8 92 2645 92 2a47 d8 2a27 350 2641 450 26a4 751 242c e002 c000

[10:11:19.500] INFO: 41600 events read in total (3404ms).
[10:11:19.506] INFO: Test took 4602ms.
[10:11:19.668] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:19.668] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 972855
[10:11:19.668] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[10:11:19.669] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:11:19.742] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:11:20.407] INFO: Expecting 41600 events.
[10:11:24.352] INFO: 41600 events read in total (3419ms).
[10:11:24.358] INFO: Test took 4616ms.
[10:11:24.517] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:24.517] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 972103
[10:11:24.517] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[10:11:24.517] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:11:24.590] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:11:25.255] INFO: Expecting 41600 events.
[10:11:29.222] INFO: 41600 events read in total (3441ms).
[10:11:29.227] INFO: Test took 4637ms.
[10:11:29.388] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:29.388] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 974274
[10:11:29.389] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[10:11:29.389] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:11:29.462] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:11:30.128] INFO: Expecting 41600 events.
[10:11:34.099] INFO: 41600 events read in total (3444ms).
[10:11:34.105] INFO: Test took 4643ms.
[10:11:34.265] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:34.265] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 974132
[10:11:34.265] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[10:11:34.266] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:11:34.338] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:11:35.003] INFO: Expecting 41600 events.
[10:11:38.987] INFO: 41600 events read in total (3457ms).
[10:11:38.993] INFO: Test took 4655ms.
[10:11:39.154] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:39.154] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 972659
[10:11:39.154] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[10:11:39.155] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:11:39.227] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:11:39.893] INFO: Expecting 41600 events.
[10:11:43.843] INFO: 41600 events read in total (3424ms).
[10:11:43.849] INFO: Test took 4621ms.
[10:11:44.011] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:44.011] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 973924
[10:11:44.011] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[10:11:44.012] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:11:44.085] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:11:44.749] INFO: Expecting 41600 events.
[10:11:48.695] INFO: 41600 events read in total (3419ms).
[10:11:48.701] INFO: Test took 4616ms.
[10:11:48.862] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:48.862] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 973198
[10:11:48.862] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[10:11:48.863] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:11:48.936] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:11:49.601] INFO: Expecting 41600 events.
[10:11:52.092] WARNING: Channel 0 ROC 2: Readback start marker after 9 readouts!

[10:11:52.092] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (9) != Token Chain Length (8)

[10:11:52.092] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[10:11:52.092] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01b 8040 40e9 415 266e 450 228d 542 2441 40e8 450 228f 40e8 445 222f 445 264d 450 2281 515 2486 554 264f 554 2a26 40e8 450 224e 514 264d 610 2a48 6c3 226f 75d 208c 81d 2a69 40e9 9 2a27 305 2249 450 2281 40e8 9a 2662 450 226d 40e8 450 226f 50c 242c 689 2461 708 2a41 712 2a65 40e9 450 228f e002 c000

[10:11:52.092] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a015 80c0 40e8 9b 226e d4 2629 450 266f 40e8 15 2040 310 2a6a 450 2681 40e8 442 2069 450 266f 549 224d 40e8 24b 2843 40a 204f 450 2664 65c 2a6d 40e8 290 2229 311 264f 352 266f 450 2689 522 2043 40e8 40a 2a4f 450 266c 40e8 450 266f 40e8 450 26a7 484 202f e002 c000

[10:11:52.092] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a016 8000 40e8 450 2682 645 248f 819 2865 40e8 450 2683 40e8 250 2262 450 266d 40e8 49 224d 203 2885 450 2682 680 244e 40e8 2c1 2847 450 268d 40e8 cb 284f 450 268f 40e8 450 266e 548 2a4e 753 2a84 40e8 15d 284d 450 26a9 e002 c000

[10:11:52.092] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a017 8040 40e8 450 266f 40e8 301 202d 450 2689 40e8 21a 248d 44c 2047 450 268d 40e8 450 2669 40e8 144 266d 450 26a0 40e8 118 2840 450 266d 40e8 8a 2461 298 206e 450 2681 60d 224e 74c 2849 40e8 450 268d e002 c000

[10:11:52.092] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a018 80b1 40e8 450 266c 71b 204f 40e9 40eb 40e9 450 2681 681 2440 40e9 35d 2487 44a 2843 44a 2424 450 266e 4c3 2669 40e9 80 2a4f 35b 268d 450 26a6 693 222d 693 264e 6d5 2667 40e9 450 266f 40e9 cb 2443 293 2665 450 2685 74d 286d 40e9 2d9 264c 30c 2885 450 26e3 552 206a 842 2a88 e002 c000

[10:11:52.092] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a019 80c0 40e9 450 2286 81c 204c 40e8 450 22ac 40e8 55 268f 298 2a45 450 228d 859 2a62 85a 224f 40e8 450 226b 68c 2045 40e8 11c 2288 450 2285 455 2668 40e8 101 224f 450 2285 54c 2843 40e8 5c 262f 2d5 2485 450 2284 459 266f 40e8 80 264d 450 22a0 e002 c000

[10:11:52.092] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01a 8000 40e9 450 2286 450 2647 40e8 450 2281 40e8 450 2269 55b 264f 55b 2a81 40e8 319 246f 450 226d 55a 2869 6d2 228a 40e8 4b 2863 9d 206d 450 2289 40e8 450 226d 40e8 51 2441 450 2287 658 2862 658 246c 74c 2667 40e8 14a 2227 14a 2621 450 228f 4da 2649 e002 c000

[10:11:53.575] INFO: 41600 events read in total (3447ms).
[10:11:53.580] INFO: Test took 4644ms.
[10:11:53.740] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:53.740] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 974758
[10:11:53.740] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[10:11:53.741] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:11:53.814] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:11:54.479] INFO: Expecting 41600 events.
[10:11:58.460] INFO: 41600 events read in total (3454ms).
[10:11:58.466] INFO: Test took 4651ms.
[10:11:58.627] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:11:58.627] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 974149
[10:11:58.627] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[10:11:58.627] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:11:58.701] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:11:59.378] INFO: Expecting 41600 events.
[10:12:03.341] INFO: 41600 events read in total (3436ms).
[10:12:03.347] INFO: Test took 4646ms.
[10:12:03.508] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:03.508] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 973269
[10:12:03.508] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[10:12:03.508] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:12:03.581] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:12:04.247] INFO: Expecting 41600 events.
[10:12:08.210] INFO: 41600 events read in total (3437ms).
[10:12:08.216] INFO: Test took 4635ms.
[10:12:08.377] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:08.377] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 974757
[10:12:08.378] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[10:12:08.378] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:12:08.451] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:12:09.116] INFO: Expecting 41600 events.
[10:12:13.079] INFO: 41600 events read in total (3436ms).
[10:12:13.085] INFO: Test took 4634ms.
[10:12:13.245] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:13.245] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 974337
[10:12:13.245] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[10:12:13.245] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:12:13.318] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:12:13.983] INFO: Expecting 41600 events.
[10:12:17.946] INFO: 41600 events read in total (3436ms).
[10:12:17.951] INFO: Test took 4633ms.
[10:12:18.113] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:18.113] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 972033
[10:12:18.113] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[10:12:18.114] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:12:18.186] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:12:18.854] INFO: Expecting 41600 events.
[10:12:22.814] INFO: 41600 events read in total (3433ms).
[10:12:22.819] INFO: Test took 4633ms.
[10:12:22.983] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:22.983] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 972722
[10:12:22.983] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[10:12:22.983] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:12:23.056] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:12:23.721] INFO: Expecting 41600 events.
[10:12:27.689] INFO: 41600 events read in total (3441ms).
[10:12:27.694] INFO: Test took 4638ms.
[10:12:27.853] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:27.853] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 972013
[10:12:27.854] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[10:12:27.854] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:12:27.926] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:12:28.592] INFO: Expecting 41600 events.
[10:12:32.558] INFO: 41600 events read in total (3439ms).
[10:12:32.564] INFO: Test took 4638ms.
[10:12:32.727] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:32.727] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 972269
[10:12:32.727] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[10:12:32.728] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:12:32.800] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:12:33.464] INFO: Expecting 41600 events.
[10:12:37.412] INFO: 41600 events read in total (3421ms).
[10:12:37.417] INFO: Test took 4617ms.
[10:12:37.578] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:37.578] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 972950
[10:12:37.578] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[10:12:37.578] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:12:37.651] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:12:38.317] INFO: Expecting 41600 events.
[10:12:42.122] INFO: 41600 events read in total (3278ms).
[10:12:42.128] INFO: Test took 4477ms.
[10:12:42.284] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:12:42.285] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 968104
[10:12:42.285] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[10:12:42.285] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:12:42.579] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 143 eff = 0.99851
[10:12:42.580] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 163 eff = 0.999207
[10:12:42.580] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 155 eff = 0.997067
[10:12:42.580] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 161 eff = 0.995048
[10:12:42.580] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 173 eff = 0.994808
[10:12:42.581] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 159 eff = 0.994423
[10:12:42.581] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 159 eff = 0.993942
[10:12:42.581] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 173 eff = 0.995697
[10:12:42.581] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 173 eff = 0.994663
[10:12:42.582] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 162 eff = 0.993822
[10:12:42.582] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 163 eff = 0.994639
[10:12:42.582] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 164 eff = 0.994615
[10:12:42.582] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 190 eff = 0.994471
[10:12:42.583] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 164 eff = 0.997284
[10:12:42.583] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 162 eff = 0.999063
[10:12:42.583] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 166 eff = 0.998462
[10:13:00.353] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[10:13:00.353] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[10:13:00.353] INFO: ----------------------------------------------------------------------
[10:13:00.353] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:13:00.353] INFO: ----------------------------------------------------------------------
[10:13:00.354] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[10:13:00.354] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[10:13:00.354] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[10:13:00.354] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[10:13:00.354] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[10:13:00.354] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[10:13:00.354] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[10:13:00.354] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[10:13:00.354] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[10:13:00.354] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[10:13:00.354] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[10:13:00.354] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[10:13:00.374] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[10:13:01.042] INFO: Expecting 208000 events.
[10:13:18.679] INFO: 208000 events read in total (17110ms).
[10:13:18.702] INFO: Test took 18328ms.
[10:13:19.633] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:13:19.633] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4541400
[10:13:19.633] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[10:13:19.633] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[10:13:20.160] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:13:20.161] INFO: number of red-efficiency pixels: 283 211 505 837 938 987 947 792 878 1085 973 935 905 479 211 262
[10:13:20.161] INFO: number of X-ray hits detected: 173600 130048 220341 335586 351187 357258 351619 302719 335134 366421 357312 334880 337233 218760 133979 168764
[10:13:20.161] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:13:20.161] INFO: number of Vcal hits detected: 207703 207770 207387 207020 206904 206798 206870 207063 206920 206676 206828 206872 206914 207435 207772 207723
[10:13:20.161] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.7 99.6 99.5 99.5 99.5 99.6 99.5 99.4 99.5 99.5 99.5 99.7 99.9 99.9
[10:13:20.161] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.5 99.5 99.4 99.5 99.5 99.5 99.4 99.4 99.5 99.5 99.7 99.9 99.9
[10:13:20.161] INFO: X-ray hit rate [MHz/cm2]: 50.9 38.1 64.6 98.4 102.9 104.7 103.1 88.7 98.2 107.4 104.7 98.2 98.8 64.1 39.3 49.5
[10:13:20.161] INFO: PixTestHighRate::doXPixelAlive() done
[10:13:20.205] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[10:13:20.205] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[10:13:20.205] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[10:13:20.205] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[10:13:20.205] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[10:13:20.205] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[10:13:20.205] INFO: PixTest:: pg_setup set to default.
[10:13:41.465] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[10:13:41.465] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[10:13:43.009] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[10:13:43.010] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 392.3mA
[10:13:43.011] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[10:13:43.011] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[10:13:43.011] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[10:13:43.011] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[10:13:43.011] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[10:13:43.011] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[10:13:43.011] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[10:13:43.012] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[10:13:43.012] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[10:13:43.012] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[10:13:43.012] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[10:13:43.012] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[10:13:43.105] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[10:13:43.105] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[10:13:43.114] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[10:13:43.114] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[10:13:43.114] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[10:13:43.114] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[10:13:43.114] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[10:13:43.114] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[10:13:43.117] QUIET: Connection to board 43 closed.
[10:13:44.319] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master