Test Date: 1973-11-29 16:33
Analysis date: 2016-10-06 18:57
Logfile
hrData_40.log
[15:52:53.196] INFO: *** Welcome to pxar ***
[15:52:53.196] INFO: *** Today: 2016/10/05
[15:52:53.221] INFO: *** Version: v1.9.0-818-g96727
[15:52:53.221] INFO: readRocDacs: data/mp601/dacParameters35_C0.dat .. data/mp601/dacParameters35_C15.dat
[15:52:53.222] INFO: readTbmDacs: data/mp601/tbmParameters_C0a.dat .. data/mp601/tbmParameters_C0b.dat
[15:52:53.222] INFO: readMaskFile: data/mp601/defaultMaskFile.dat
[15:52:53.223] INFO: MASKED Roc 5 col/row: 3 4
[15:52:53.223] INFO: readTrimFile: data/mp601/trimParameters35_C0.dat .. data/mp601/trimParameters35_C15.dat
[15:52:53.269] INFO: masking Roc 5 col/row: 3 4
[15:52:53.372] INFO: clk: 4
[15:52:53.372] INFO: ctr: 4
[15:52:53.372] INFO: sda: 19
[15:52:53.372] INFO: tin: 9
[15:52:53.372] INFO: level: 15
[15:52:53.372] INFO: triggerdelay: 0
[15:52:53.372] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:52:53.372] INFO: Log level: DEBUG
[15:52:53.383] QUIET: Connection to board DTB_WRPRHI opened.
[15:52:53.387] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:52:53.390] INFO: RPC call hashes of host and DTB match: 398089610
[15:52:54.994] INFO: DUT info:
[15:52:54.994] INFO: The DUT currently contains the following objects:
[15:52:54.994] INFO: 2 TBM Cores tbm08c (2 ON)
[15:52:54.994] INFO: TBM Core alpha (0): 7 registers set
[15:52:54.994] INFO: TBM Core beta (1): 7 registers set
[15:52:54.994] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:52:54.994] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:54.994] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:54.994] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:54.994] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:54.994] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:54.994] INFO: ROC 5: 19 DACs set, Pixels: 1 masked, 0 active.
[15:52:54.994] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:54.994] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:54.994] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:54.994] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:54.994] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:54.994] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:54.994] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:54.994] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:54.994] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:54.994] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:52:54.995] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:52:54.996] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:52:54.997] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:52:54.998] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:52:55.000] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31301632
[15:52:55.000] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1c2d0e0
[15:52:55.000] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x19cf370
[15:52:55.000] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f14cdd94010
[15:52:55.000] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f14d3fff510
[15:52:55.000] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31309824 fPxarMemory = 0x7f14cdd94010
[15:52:55.002] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 373mA
[15:52:55.003] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[15:52:55.003] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: 0.4 C
[15:52:55.500] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x201c090
[15:52:55.567] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:52:55.568] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:52:55.568] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:52:55.573] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:52:55.573] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:52:55.574] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:52:55.586] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:52:55.586] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:52:55.605] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:52:55.605] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:52:55.618] INFO: PixTestCmd::init()
[15:52:55.630] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:52:55.630] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:52:55.630] INFO: readGainPedestalParameters data/mp601/phCalibrationFitErr35_C0.dat .. data/mp601/phCalibrationFitErr35_C15.dat
[15:52:55.878] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:52:55.878] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:52:55.893] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:52:55.900] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:52:55.900] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:52:55.900] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:52:55.923] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:52:55.923] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:52:55.923] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:52:55.923] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:52:55.923] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:52:55.929] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:52:55.929] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:52:55.951] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:52:55.951] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:52:55.951] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:52:55.951] INFO: readReadbackCal: data/mp601/readbackCal_C0.dat .. data/mp601/readbackCal_C15.dat
[15:52:55.961] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:52:55.969] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:52:55.969] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:52:55.969] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:52:55.969] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:52:55.991] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:52:55.991] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:52:57.698] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[15:52:57.698] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[15:52:57.698] INFO: ----------------------------------------------------------------------
[15:52:57.698] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:52:57.698] INFO: ----------------------------------------------------------------------
[15:52:57.701] INFO: ROC 5 masking hot pixel 3/4
[15:52:57.701] INFO: ROC 5 masking pixel 3/4
[15:52:58.672] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:53:10.477] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:53:10.481] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:53:37.622] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1163148 events.
[15:53:44.582] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1163148, pixels seen in all events: 6294281
[15:53:44.772] INFO: Resuming triggers.
[15:53:56.579] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:53:56.615] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:54:23.702] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1163219 events.
[15:54:30.607] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1163219, pixels seen in all events: 6292429
[15:54:30.786] INFO: Resuming triggers.
[15:54:42.596] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[15:54:42.630] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:55:09.455] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1163503 events.
[15:55:16.352] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1163503, pixels seen in all events: 6289329
[15:55:16.615] INFO: Resuming triggers.
[15:55:28.423] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[15:55:28.458] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:55:55.500] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1163366 events.
[15:56:02.502] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1163366, pixels seen in all events: 6291162
[15:56:02.599] INFO: Resuming triggers.
[15:56:14.411] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[15:56:14.448] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:56:41.449] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1163663 events.
[15:56:48.355] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1163663, pixels seen in all events: 6287178
[15:56:48.618] INFO: Resuming triggers.
[15:57:00.428] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[15:57:00.468] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:57:27.575] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1163429 events.
[15:57:34.464] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1163429, pixels seen in all events: 6289952
[15:57:34.645] INFO: Resuming triggers.
[15:57:46.457] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[15:57:46.490] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:58:13.328] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1163689 events.
[15:58:20.219] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1163689, pixels seen in all events: 6286580
[15:58:20.481] INFO: Resuming triggers.
[15:58:32.295] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[15:58:32.329] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:58:59.327] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1163798 events.
[15:59:06.306] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1163798, pixels seen in all events: 6285844
[15:59:06.490] INFO: Resuming triggers.
[15:59:12.149] INFO: data taking finished, elapsed time: 100 seconds.
[15:59:12.345] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:59:25.345] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 557413 events.
[15:59:28.643] DEBUG: <PixTestXray.cc/processData:L823> # events read: 557413, pixels seen in all events: 3008336
[15:59:28.751] INFO: PixTest:: pg_setup set to default.
[15:59:28.787] INFO: PixTestXray::doPhRun() done
[15:59:38.090] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:59:38.090] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hMap_Ag_C12_V0 -> hMap_Ag_mod
[15:59:40.354] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:59:40.355] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 377mA
[15:59:40.355] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 469.5mA
[15:59:40.355] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:59:40.355] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:59:40.356] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:59:40.356] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:59:40.356] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:59:40.356] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:59:40.356] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:59:40.356] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:59:40.356] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:59:40.356] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:59:40.356] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:59:40.356] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:59:40.356] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:59:40.356] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:59:40.356] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:59:40.356] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:59:40.357] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:59:40.357] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:59:40.357] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:59:40.662] QUIET: Connection to board 58 closed.
[15:59:40.741] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[15:59:53.672] INFO: *** Welcome to pxar ***
[15:59:53.672] INFO: *** Today: 2016/10/05
[15:59:53.699] INFO: *** Version: v1.9.0-818-g96727
[15:59:53.699] INFO: readRocDacs: data/mp601/dacParameters35_C0.dat .. data/mp601/dacParameters35_C15.dat
[15:59:53.700] INFO: readTbmDacs: data/mp601/tbmParameters_C0a.dat .. data/mp601/tbmParameters_C0b.dat
[15:59:53.700] INFO: readMaskFile: data/mp601/defaultMaskFile.dat
[15:59:53.700] INFO: MASKED Roc 5 col/row: 3 4
[15:59:53.700] INFO: readTrimFile: data/mp601/trimParameters35_C0.dat .. data/mp601/trimParameters35_C15.dat
[15:59:53.747] INFO: masking Roc 5 col/row: 3 4
[15:59:53.849] INFO: clk: 4
[15:59:53.850] INFO: ctr: 4
[15:59:53.850] INFO: sda: 19
[15:59:53.850] INFO: tin: 9
[15:59:53.850] INFO: level: 15
[15:59:53.850] INFO: triggerdelay: 0
[15:59:53.850] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:59:53.850] INFO: Log level: DEBUG
[15:59:53.860] QUIET: Connection to board DTB_WRPRHI opened.
[15:59:53.864] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:59:53.866] INFO: RPC call hashes of host and DTB match: 398089610
[15:59:55.468] INFO: DUT info:
[15:59:55.468] INFO: The DUT currently contains the following objects:
[15:59:55.468] INFO: 2 TBM Cores tbm08c (2 ON)
[15:59:55.468] INFO: TBM Core alpha (0): 7 registers set
[15:59:55.468] INFO: TBM Core beta (1): 7 registers set
[15:59:55.468] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:59:55.468] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:55.468] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:55.468] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:55.468] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:55.469] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:55.469] INFO: ROC 5: 19 DACs set, Pixels: 1 masked, 0 active.
[15:59:55.469] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:55.469] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:55.469] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:55.469] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:55.469] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:55.469] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:55.469] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:55.469] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:55.469] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:55.469] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:59:55.469] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:59:55.469] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:59:55.469] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:59:55.469] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:59:55.469] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:59:55.469] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:59:55.469] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:59:55.469] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:59:55.469] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:59:55.469] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:59:55.469] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:59:55.469] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:59:55.469] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:59:55.469] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:59:55.470] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:59:55.471] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:59:55.472] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:59:55.475] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31350784
[15:59:55.475] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x267e0e0
[15:59:55.475] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2420370
[15:59:55.475] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f2135d94010
[15:59:55.475] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f213bfff510
[15:59:55.475] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31358976 fPxarMemory = 0x7f2135d94010
[15:59:55.476] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 372.2mA
[15:59:55.477] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[15:59:55.477] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -1.5 C
[15:59:55.971] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2a6cf00
[15:59:56.038] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:59:56.038] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:59:56.038] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:59:56.044] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:59:56.044] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:59:56.044] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:59:56.055] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:59:56.055] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:59:56.079] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:59:56.079] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:59:56.092] INFO: PixTestCmd::init()
[15:59:56.117] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:59:56.117] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:59:56.117] INFO: readGainPedestalParameters data/mp601/phCalibrationFitErr35_C0.dat .. data/mp601/phCalibrationFitErr35_C15.dat
[15:59:56.374] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:59:56.374] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:59:56.390] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:59:56.399] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:59:56.399] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:59:56.399] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:59:56.426] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:59:56.426] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:59:56.426] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:59:56.426] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:59:56.426] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:59:56.433] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:59:56.433] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:59:56.459] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:59:56.459] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:59:56.459] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:59:56.459] INFO: readReadbackCal: data/mp601/readbackCal_C0.dat .. data/mp601/readbackCal_C15.dat
[15:59:56.479] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:59:56.487] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:59:56.487] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:59:56.487] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:59:56.487] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:59:56.507] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:59:56.508] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:59:58.970] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[15:59:58.970] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[15:59:58.970] INFO: ----------------------------------------------------------------------
[15:59:58.970] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:59:58.970] INFO: ----------------------------------------------------------------------
[15:59:58.973] INFO: ROC 5 masking hot pixel 3/4
[15:59:58.973] INFO: ROC 5 masking pixel 3/4
[15:59:59.944] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:00:08.397] INFO: run duration 8 seconds, buffer almost full (81%), pausing triggers.
[16:00:08.400] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:00:33.840] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 832858 events.
[16:00:45.406] DEBUG: <PixTestXray.cc/processData:L823> # events read: 832858, pixels seen in all events: 10259104
[16:00:45.606] INFO: Resuming triggers.
[16:00:54.063] INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[16:00:54.173] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:01:19.711] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 833138 events.
[16:01:31.246] DEBUG: <PixTestXray.cc/processData:L823> # events read: 833138, pixels seen in all events: 10254120
[16:01:31.465] INFO: Resuming triggers.
[16:01:39.925] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[16:01:39.961] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:02:05.393] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 833483 events.
[16:02:16.914] DEBUG: <PixTestXray.cc/processData:L823> # events read: 833483, pixels seen in all events: 10250016
[16:02:17.129] INFO: Resuming triggers.
[16:02:25.584] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[16:02:25.658] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:02:51.104] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 832929 events.
[16:03:02.663] DEBUG: <PixTestXray.cc/processData:L823> # events read: 832929, pixels seen in all events: 10256405
[16:03:02.931] INFO: Resuming triggers.
[16:03:11.390] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[16:03:11.425] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:03:36.851] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 833335 events.
[16:03:48.384] DEBUG: <PixTestXray.cc/processData:L823> # events read: 833335, pixels seen in all events: 10252131
[16:03:48.652] INFO: Resuming triggers.
[16:03:57.117] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[16:03:57.152] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:04:22.606] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 833914 events.
[16:04:34.139] DEBUG: <PixTestXray.cc/processData:L823> # events read: 833914, pixels seen in all events: 10245204
[16:04:34.355] INFO: Resuming triggers.
[16:04:42.818] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[16:04:42.896] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:05:08.343] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 833743 events.
[16:05:19.872] DEBUG: <PixTestXray.cc/processData:L823> # events read: 833743, pixels seen in all events: 10246805
[16:05:20.138] INFO: Resuming triggers.
[16:05:28.600] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[16:05:28.634] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:05:54.124] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 833594 events.
[16:06:05.714] DEBUG: <PixTestXray.cc/processData:L823> # events read: 833594, pixels seen in all events: 10247974
[16:06:05.980] INFO: Resuming triggers.
[16:06:14.442] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[16:06:14.477] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:06:39.967] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 833641 events.
[16:06:51.617] DEBUG: <PixTestXray.cc/processData:L823> # events read: 833641, pixels seen in all events: 10248150
[16:06:51.904] INFO: Resuming triggers.
[16:07:00.368] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[16:07:00.400] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:07:25.878] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 833828 events.
[16:07:37.625] DEBUG: <PixTestXray.cc/processData:L823> # events read: 833828, pixels seen in all events: 10246278
[16:07:37.969] INFO: Resuming triggers.
[16:07:46.442] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[16:07:46.479] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:08:12.043] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 834653 events.
[16:08:23.579] DEBUG: <PixTestXray.cc/processData:L823> # events read: 834653, pixels seen in all events: 10235581
[16:08:23.797] INFO: Resuming triggers.
[16:08:30.846] INFO: data taking finished, elapsed time: 100 seconds.
[16:08:31.042] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:08:52.336] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 694435 events.
[16:09:01.979] DEBUG: <PixTestXray.cc/processData:L823> # events read: 694435, pixels seen in all events: 8526779
[16:09:02.140] INFO: PixTest:: pg_setup set to default.
[16:09:02.177] INFO: PixTestXray::doPhRun() done
[16:09:10.114] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:09:10.114] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hMap_Ag_C13_V0 -> hMap_Ag_mod
[16:09:11.578] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:09:11.579] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 377mA
[16:09:11.579] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 469.5mA
[16:09:11.580] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.5 C
[16:09:11.580] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:09:11.580] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:09:11.580] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:09:11.580] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:09:11.580] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:09:11.580] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:09:11.580] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:09:11.580] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:09:11.580] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:09:11.580] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:09:11.580] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:09:11.580] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:09:11.580] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:09:11.581] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:09:11.581] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:09:11.581] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:09:11.581] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:09:11.581] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:09:11.914] QUIET: Connection to board 58 closed.
[16:09:11.994] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[15:45:11.090] INFO: *** Welcome to pxar ***
[15:45:11.091] INFO: *** Today: 2016/10/05
[15:45:11.115] INFO: *** Version: v1.9.0-818-g96727
[15:45:11.115] INFO: readRocDacs: data/mp601/dacParameters35_C0.dat .. data/mp601/dacParameters35_C15.dat
[15:45:11.116] INFO: readTbmDacs: data/mp601/tbmParameters_C0a.dat .. data/mp601/tbmParameters_C0b.dat
[15:45:11.116] INFO: readMaskFile: data/mp601/defaultMaskFile.dat
[15:45:11.116] INFO: MASKED Roc 5 col/row: 3 4
[15:45:11.116] INFO: readTrimFile: data/mp601/trimParameters35_C0.dat .. data/mp601/trimParameters35_C15.dat
[15:45:11.167] INFO: masking Roc 5 col/row: 3 4
[15:45:11.272] INFO: clk: 4
[15:45:11.272] INFO: ctr: 4
[15:45:11.272] INFO: sda: 19
[15:45:11.272] INFO: tin: 9
[15:45:11.272] INFO: level: 15
[15:45:11.272] INFO: triggerdelay: 0
[15:45:11.272] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:45:11.272] INFO: Log level: DEBUG
[15:45:11.283] QUIET: Connection to board DTB_WRPRHI opened.
[15:45:11.287] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:45:11.289] INFO: RPC call hashes of host and DTB match: 398089610
[15:45:12.893] INFO: DUT info:
[15:45:12.893] INFO: The DUT currently contains the following objects:
[15:45:12.893] INFO: 2 TBM Cores tbm08c (2 ON)
[15:45:12.893] INFO: TBM Core alpha (0): 7 registers set
[15:45:12.893] INFO: TBM Core beta (1): 7 registers set
[15:45:12.893] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:45:12.893] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:45:12.893] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:45:12.893] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:45:12.893] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:45:12.893] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:45:12.893] INFO: ROC 5: 19 DACs set, Pixels: 1 masked, 0 active.
[15:45:12.893] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:45:12.893] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:45:12.893] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:45:12.893] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:45:12.893] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:45:12.893] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:45:12.893] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:45:12.893] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:45:12.893] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:45:12.893] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:45:12.894] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:45:12.895] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:45:12.896] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:45:12.897] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:45:12.899] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31375360
[15:45:12.899] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x10360e0
[15:45:12.899] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xdd8370
[15:45:12.899] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fa2e9d94010
[15:45:12.899] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fa2effff510
[15:45:12.899] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31383552 fPxarMemory = 0x7fa2e9d94010
[15:45:12.901] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 373mA
[15:45:12.902] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 469.5mA
[15:45:12.902] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[15:45:13.403] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1425080
[15:45:13.468] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:45:13.468] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:45:13.468] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:45:13.474] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:45:13.474] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:45:13.474] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:45:13.484] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:45:13.484] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:45:13.492] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:45:13.492] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:45:13.514] INFO: PixTestCmd::init()
[15:45:13.535] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:45:13.535] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:45:13.535] INFO: readGainPedestalParameters data/mp601/phCalibrationFitErr35_C0.dat .. data/mp601/phCalibrationFitErr35_C15.dat
[15:45:13.793] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:45:13.793] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:45:13.807] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:45:13.813] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:45:13.813] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:45:13.813] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:45:13.838] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:45:13.838] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:45:13.838] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:45:13.838] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:45:13.838] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:45:13.845] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:45:13.845] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:45:13.866] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:45:13.866] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:45:13.866] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:45:13.866] INFO: readReadbackCal: data/mp601/readbackCal_C0.dat .. data/mp601/readbackCal_C15.dat
[15:45:13.876] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:45:13.885] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:45:13.885] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:45:13.885] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:45:13.885] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:45:13.909] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:45:13.909] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:45:17.957] INFO: ######################################################################
[15:45:17.957] INFO: PixTestAlive::doTest()
[15:45:17.957] INFO: ######################################################################
[15:45:17.960] INFO: ----------------------------------------------------------------------
[15:45:17.960] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:45:17.960] INFO: ----------------------------------------------------------------------
[15:45:17.963] INFO: ROC 5 masking pixel 3/4
[15:45:17.963] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:45:18.342] INFO: Expecting 41600 events.
[15:45:22.740] INFO: 41600 events read in total (3679ms).
[15:45:22.887] INFO: Test took 4924ms.
[15:45:22.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:22.897] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:45:22.897] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:45:23.177] INFO: PixTestAlive::aliveTest() done
[15:45:23.177] INFO: number of dead pixels (per ROC): 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[15:45:23.177] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 22 22 26 28 42 42 37 36 42 43 42 33 37 28 19 16
[15:45:23.177] INFO: ROC 5 masking pixel 3/4
[15:45:23.203] INFO: ----------------------------------------------------------------------
[15:45:23.203] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:45:23.203] INFO: ----------------------------------------------------------------------
[15:45:23.205] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:45:23.587] INFO: Expecting 41600 events.
[15:45:26.700] INFO: 41600 events read in total (2398ms).
[15:45:26.701] INFO: Test took 3496ms.
[15:45:26.701] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:26.701] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:45:26.701] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:45:26.701] INFO: ROC 5 masking pixel 3/4
[15:45:26.701] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:45:27.123] INFO: PixTestAlive::maskTest() done
[15:45:27.123] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:45:27.124] INFO: ROC 5 masking pixel 3/4
[15:45:27.147] INFO: ----------------------------------------------------------------------
[15:45:27.147] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:45:27.147] INFO: ----------------------------------------------------------------------
[15:45:27.149] INFO: ROC 5 masking pixel 3/4
[15:45:27.149] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:45:27.526] INFO: Expecting 41600 events.
[15:45:31.889] INFO: 41600 events read in total (3648ms).
[15:45:31.890] INFO: Test took 4741ms.
[15:45:31.901] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:31.901] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:45:31.902] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:45:32.273] INFO: PixTestAlive::addressDecodingTest() done
[15:45:32.273] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:45:32.273] INFO: ROC 5 masking pixel 3/4
[15:45:32.273] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:45:33.826] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:45:33.826] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:45:33.826] INFO: ----------------------------------------------------------------------
[15:45:33.826] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:45:33.826] INFO: ----------------------------------------------------------------------
[15:45:33.966] INFO: Expecting 768 events.
[15:45:35.100] INFO: 768 events read in total (419ms).
[15:45:35.100] INFO: Test took 1268ms.
[15:45:35.104] INFO: ROC 5 masking pixel 3/4
[15:45:35.106] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:45:35.904] INFO: Expecting 41600 events.
[15:45:39.202] INFO: 41600 events read in total (2771ms).
[15:45:39.207] INFO: Test took 4101ms.
[15:45:39.284] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:39.284] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 440867
[15:45:39.284] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:45:39.284] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:45:39.320] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:45:39.986] INFO: Expecting 41600 events.
[15:45:43.406] INFO: 41600 events read in total (2894ms).
[15:45:43.409] INFO: Test took 4089ms.
[15:45:43.483] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:43.483] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 445072
[15:45:43.483] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:45:43.483] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:45:43.520] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:45:44.185] INFO: Expecting 41600 events.
[15:45:47.647] INFO: 41600 events read in total (2935ms).
[15:45:47.651] INFO: Test took 4131ms.
[15:45:47.724] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:47.724] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 446227
[15:45:47.724] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:45:47.725] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:45:47.762] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:45:48.427] INFO: Expecting 41600 events.
[15:45:51.891] INFO: 41600 events read in total (2937ms).
[15:45:51.894] INFO: Test took 4132ms.
[15:45:51.968] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:51.968] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 447732
[15:45:51.968] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:45:51.968] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:45:52.006] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:45:52.671] INFO: Expecting 41600 events.
[15:45:56.147] INFO: 41600 events read in total (2949ms).
[15:45:56.151] INFO: Test took 4145ms.
[15:45:56.225] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:56.225] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 447319
[15:45:56.225] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:45:56.225] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:45:56.263] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:45:56.928] INFO: Expecting 41600 events.
[15:46:00.400] INFO: 41600 events read in total (2945ms).
[15:46:00.404] INFO: Test took 4141ms.
[15:46:00.478] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:00.478] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 447882
[15:46:00.478] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:46:00.479] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:46:00.516] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:46:01.182] INFO: Expecting 41600 events.
[15:46:04.652] INFO: 41600 events read in total (2944ms).
[15:46:04.655] INFO: Test took 4139ms.
[15:46:04.729] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:04.729] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 446826
[15:46:04.729] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:46:04.730] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:46:04.767] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:46:05.433] INFO: Expecting 41600 events.
[15:46:08.907] INFO: 41600 events read in total (2947ms).
[15:46:08.911] INFO: Test took 4144ms.
[15:46:08.985] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:08.985] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 445990
[15:46:08.985] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:46:08.986] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:46:09.023] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:46:09.688] INFO: Expecting 41600 events.
[15:46:13.171] INFO: 41600 events read in total (2956ms).
[15:46:13.174] INFO: Test took 4151ms.
[15:46:13.247] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:13.247] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 447098
[15:46:13.247] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:46:13.248] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:46:13.286] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:46:13.953] INFO: Expecting 41600 events.
[15:46:17.438] INFO: 41600 events read in total (2959ms).
[15:46:17.441] INFO: Test took 4155ms.
[15:46:17.515] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:17.515] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 446859
[15:46:17.515] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:46:17.515] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:46:17.553] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:46:18.219] INFO: Expecting 41600 events.
[15:46:21.710] INFO: 41600 events read in total (2965ms).
[15:46:21.714] INFO: Test took 4161ms.
[15:46:21.788] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:21.788] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 447656
[15:46:21.788] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:46:21.788] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:46:21.826] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:46:22.491] INFO: Expecting 41600 events.
[15:46:25.977] INFO: 41600 events read in total (2959ms).
[15:46:25.981] INFO: Test took 4155ms.
[15:46:26.055] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:26.055] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 446733
[15:46:26.055] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:46:26.055] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:46:26.093] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:46:26.759] INFO: Expecting 41600 events.
[15:46:30.231] INFO: 41600 events read in total (2946ms).
[15:46:30.234] INFO: Test took 4141ms.
[15:46:30.308] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:30.308] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 446053
[15:46:30.308] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:46:30.308] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:46:30.346] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:46:31.011] INFO: Expecting 41600 events.
[15:46:34.492] INFO: 41600 events read in total (2954ms).
[15:46:34.495] INFO: Test took 4149ms.
[15:46:34.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:34.568] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 447367
[15:46:34.568] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:46:34.569] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:46:34.607] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:46:35.272] INFO: Expecting 41600 events.
[15:46:38.758] INFO: 41600 events read in total (2960ms).
[15:46:38.761] INFO: Test took 4154ms.
[15:46:38.835] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:38.835] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 446270
[15:46:38.835] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:46:38.836] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:46:38.873] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:46:39.539] INFO: Expecting 41600 events.
[15:46:43.013] INFO: 41600 events read in total (2948ms).
[15:46:43.016] INFO: Test took 4143ms.
[15:46:43.090] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:43.090] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 446541
[15:46:43.090] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:46:43.090] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:46:43.129] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:46:43.794] INFO: Expecting 41600 events.
[15:46:47.268] INFO: 41600 events read in total (2948ms).
[15:46:47.272] INFO: Test took 4143ms.
[15:46:47.345] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:47.345] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 446335
[15:46:47.345] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:46:47.346] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:46:47.384] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:46:48.050] INFO: Expecting 41600 events.
[15:46:51.536] INFO: 41600 events read in total (2959ms).
[15:46:51.539] INFO: Test took 4155ms.
[15:46:51.614] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:51.614] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 446937
[15:46:51.614] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:46:51.615] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:46:51.653] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:46:52.318] INFO: Expecting 41600 events.
[15:46:55.761] INFO: 41600 events read in total (2916ms).
[15:46:55.764] INFO: Test took 4111ms.
[15:46:55.840] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:55.840] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 445318
[15:46:55.840] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:46:55.840] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:46:55.878] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:46:56.543] INFO: Expecting 41600 events.
[15:46:59.854] INFO: 41600 events read in total (2785ms).
[15:46:59.858] INFO: Test took 3980ms.
[15:46:59.932] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:59.932] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 440713
[15:46:59.932] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:46:59.932] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:47:00.191] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 173 eff = 0.999567
[15:47:00.191] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 171 eff = 0.999736
[15:47:00.191] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 173 eff = 0.999471
[15:47:00.192] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 160 eff = 0.999111
[15:47:00.192] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 171 eff = 0.999087
[15:47:00.192] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 163 eff = 0.998726
[15:47:00.192] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 157 eff = 0.999159
[15:47:00.192] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 164 eff = 0.999399
[15:47:00.192] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 171 eff = 0.999375
[15:47:00.192] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 156 eff = 0.999111
[15:47:00.193] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 146 eff = 0.998918
[15:47:00.193] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 150 eff = 0.999087
[15:47:00.193] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 164 eff = 0.999087
[15:47:00.193] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 157 eff = 0.999351
[15:47:00.193] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 128 eff = 0.999639
[15:47:00.193] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 175 eff = 0.999663
[15:47:25.154] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:47:25.154] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:47:25.154] INFO: ----------------------------------------------------------------------
[15:47:25.154] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:47:25.154] INFO: ----------------------------------------------------------------------
[15:47:25.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:47:25.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:47:25.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:47:25.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:47:25.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:47:25.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:47:25.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:47:25.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:47:25.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:47:25.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:47:25.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:47:25.154] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:47:25.174] INFO: ROC 5 masking pixel 3/4
[15:47:25.175] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:47:25.841] INFO: Expecting 208000 events.
[15:47:39.062] INFO: 208000 events read in total (12694ms).
[15:47:39.073] INFO: Test took 13898ms.
[15:47:39.406] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:47:39.406] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1954740
[15:47:39.406] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:47:39.407] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:47:39.774] INFO: number of dead pixels (per ROC): 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[15:47:39.774] INFO: number of red-efficiency pixels: 106 71 126 198 204 229 224 183 169 248 263 225 265 177 76 93
[15:47:39.774] INFO: number of X-ray hits detected: 88383 57909 93540 140413 146827 145580 142672 126837 126615 151970 149009 141137 147571 98964 57275 73479
[15:47:39.774] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:47:39.774] INFO: number of Vcal hits detected: 207884 207928 207872 207797 207787 207714 207771 207810 207824 207743 207730 207764 207719 207811 207923 207903
[15:47:39.774] 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 100.0
[15:47:39.774] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[15:47:39.774] INFO: X-ray hit rate [MHz/cm2]: 25.9 17.0 27.4 41.2 43.0 42.7 41.8 37.2 37.1 44.5 43.7 41.4 43.3 29.0 16.8 21.5
[15:47:39.774] INFO: PixTestHighRate::doXPixelAlive() done
[15:47:39.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:47:39.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:47:39.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:47:39.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:47:39.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:47:39.819] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:47:39.819] INFO: PixTest:: pg_setup set to default.
[15:47:45.178] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:47:45.178] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[15:47:46.586] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:47:46.587] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 377mA
[15:47:46.587] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[15:47:46.587] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.5 C
[15:47:46.587] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:47:46.587] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:47:46.588] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:47:46.588] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:47:46.588] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:47:46.588] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:47:46.588] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:47:46.588] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:47:46.588] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:47:46.588] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:47:46.677] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:47:46.677] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:47:46.686] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:47:46.686] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:47:46.686] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:47:46.686] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:47:46.686] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:47:46.686] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:47:46.689] QUIET: Connection to board 58 closed.
[15:47:46.768] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[15:40:41.355] INFO: *** Welcome to pxar ***
[15:40:41.355] INFO: *** Today: 2016/10/05
[15:40:41.381] INFO: *** Version: v1.9.0-818-g96727
[15:40:41.381] INFO: readRocDacs: data/mp601/dacParameters35_C0.dat .. data/mp601/dacParameters35_C15.dat
[15:40:41.382] INFO: readTbmDacs: data/mp601/tbmParameters_C0a.dat .. data/mp601/tbmParameters_C0b.dat
[15:40:41.382] INFO: readMaskFile: data/mp601/defaultMaskFile.dat
[15:40:41.382] INFO: MASKED Roc 5 col/row: 3 4
[15:40:41.382] INFO: readTrimFile: data/mp601/trimParameters35_C0.dat .. data/mp601/trimParameters35_C15.dat
[15:40:41.435] INFO: masking Roc 5 col/row: 3 4
[15:40:41.538] INFO: clk: 4
[15:40:41.538] INFO: ctr: 4
[15:40:41.538] INFO: sda: 19
[15:40:41.538] INFO: tin: 9
[15:40:41.538] INFO: level: 15
[15:40:41.538] INFO: triggerdelay: 0
[15:40:41.538] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:40:41.538] INFO: Log level: DEBUG
[15:40:41.548] QUIET: Connection to board DTB_WRPRHI opened.
[15:40:41.552] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:40:41.554] INFO: RPC call hashes of host and DTB match: 398089610
[15:40:43.157] INFO: DUT info:
[15:40:43.157] INFO: The DUT currently contains the following objects:
[15:40:43.157] INFO: 2 TBM Cores tbm08c (2 ON)
[15:40:43.157] INFO: TBM Core alpha (0): 7 registers set
[15:40:43.157] INFO: TBM Core beta (1): 7 registers set
[15:40:43.157] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:40:43.157] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:43.157] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:43.157] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:43.157] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:43.157] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:43.157] INFO: ROC 5: 19 DACs set, Pixels: 1 masked, 0 active.
[15:40:43.157] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:43.157] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:43.157] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:43.157] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:43.157] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:43.157] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:43.157] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:43.157] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:43.157] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:43.157] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:40:43.158] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:40:43.159] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:40:43.160] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:40:43.161] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:40:43.163] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31379456
[15:40:43.163] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1f4e0e0
[15:40:43.163] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1cf0370
[15:40:43.163] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f9ab1d94010
[15:40:43.163] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f9ab7fff510
[15:40:43.163] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31387648 fPxarMemory = 0x7f9ab1d94010
[15:40:43.164] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 373mA
[15:40:43.165] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 469.5mA
[15:40:43.165] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:40:43.659] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x233d090
[15:40:43.720] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:40:43.720] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:40:43.720] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:40:43.726] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:40:43.726] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:40:43.726] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:40:43.735] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:40:43.735] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:40:43.757] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:40:43.757] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:40:43.774] INFO: PixTestCmd::init()
[15:40:43.794] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:40:43.794] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:40:43.794] INFO: readGainPedestalParameters data/mp601/phCalibrationFitErr35_C0.dat .. data/mp601/phCalibrationFitErr35_C15.dat
[15:40:44.040] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:40:44.040] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:40:44.054] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:40:44.060] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:40:44.060] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:40:44.061] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:40:44.083] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:40:44.083] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:40:44.083] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:40:44.083] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:40:44.083] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:40:44.093] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:40:44.093] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:40:44.113] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:40:44.113] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:40:44.113] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:40:44.113] INFO: readReadbackCal: data/mp601/readbackCal_C0.dat .. data/mp601/readbackCal_C15.dat
[15:40:44.125] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:40:44.133] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:40:44.133] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:40:44.133] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:40:44.133] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:40:44.155] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:40:44.155] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:40:50.749] INFO: ######################################################################
[15:40:50.749] INFO: PixTestAlive::doTest()
[15:40:50.749] INFO: ######################################################################
[15:40:50.752] INFO: ----------------------------------------------------------------------
[15:40:50.752] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:40:50.752] INFO: ----------------------------------------------------------------------
[15:40:50.755] INFO: ROC 5 masking pixel 3/4
[15:40:50.755] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:40:51.134] INFO: Expecting 41600 events.
[15:40:55.520] INFO: 41600 events read in total (3668ms).
[15:40:55.667] INFO: Test took 4912ms.
[15:40:55.679] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:55.679] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:40:55.679] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:40:55.961] INFO: PixTestAlive::aliveTest() done
[15:40:55.961] INFO: number of dead pixels (per ROC): 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[15:40:55.961] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 37 19 43 43 42 68 52 36 51 59 54 60 55 36 19 25
[15:40:55.961] INFO: ROC 5 masking pixel 3/4
[15:40:55.988] INFO: ----------------------------------------------------------------------
[15:40:55.988] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:40:55.988] INFO: ----------------------------------------------------------------------
[15:40:55.990] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:40:56.365] INFO: Expecting 41600 events.
[15:40:59.471] INFO: 41600 events read in total (2391ms).
[15:40:59.472] INFO: Test took 3482ms.
[15:40:59.472] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:59.472] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:40:59.472] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:40:59.473] INFO: ROC 5 masking pixel 3/4
[15:40:59.473] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:40:59.894] INFO: PixTestAlive::maskTest() done
[15:40:59.894] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:40:59.894] INFO: ROC 5 masking pixel 3/4
[15:40:59.918] INFO: ----------------------------------------------------------------------
[15:40:59.918] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:40:59.918] INFO: ----------------------------------------------------------------------
[15:40:59.920] INFO: ROC 5 masking pixel 3/4
[15:40:59.920] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:00.297] INFO: Expecting 41600 events.
[15:41:04.676] INFO: 41600 events read in total (3664ms).
[15:41:04.677] INFO: Test took 4757ms.
[15:41:04.688] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:04.688] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:41:04.688] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:41:05.060] INFO: PixTestAlive::addressDecodingTest() done
[15:41:05.060] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:41:05.060] INFO: ROC 5 masking pixel 3/4
[15:41:05.060] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:41:13.402] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:41:13.402] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:41:13.402] INFO: ----------------------------------------------------------------------
[15:41:13.402] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:41:13.402] INFO: ----------------------------------------------------------------------
[15:41:13.541] INFO: Expecting 768 events.
[15:41:14.674] INFO: 768 events read in total (418ms).
[15:41:14.674] INFO: Test took 1266ms.
[15:41:14.678] INFO: ROC 5 masking pixel 3/4
[15:41:14.680] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:15.478] INFO: Expecting 41600 events.
[15:41:18.981] INFO: 41600 events read in total (2977ms).
[15:41:18.985] INFO: Test took 4305ms.
[15:41:19.092] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:19.092] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 607832
[15:41:19.092] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:41:19.093] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:19.139] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:19.804] INFO: Expecting 41600 events.
[15:41:23.440] INFO: 41600 events read in total (3109ms).
[15:41:23.444] INFO: Test took 4305ms.
[15:41:23.548] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:23.548] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 616763
[15:41:23.548] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:41:23.548] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:23.597] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:24.262] INFO: Expecting 41600 events.
[15:41:27.931] INFO: 41600 events read in total (3143ms).
[15:41:27.935] INFO: Test took 4338ms.
[15:41:28.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:28.039] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 617809
[15:41:28.039] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:41:28.040] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:28.089] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:28.754] INFO: Expecting 41600 events.
[15:41:32.420] INFO: 41600 events read in total (3139ms).
[15:41:32.425] INFO: Test took 4336ms.
[15:41:32.528] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:32.528] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 618741
[15:41:32.528] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:41:32.529] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:32.578] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:33.243] INFO: Expecting 41600 events.
[15:41:36.910] INFO: 41600 events read in total (3140ms).
[15:41:36.915] INFO: Test took 4337ms.
[15:41:37.020] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:37.020] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 619612
[15:41:37.021] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:41:37.021] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:37.070] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:37.735] INFO: Expecting 41600 events.
[15:41:41.395] INFO: 41600 events read in total (3133ms).
[15:41:41.399] INFO: Test took 4329ms.
[15:41:41.503] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:41.503] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 618597
[15:41:41.503] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:41:41.504] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:41.553] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:42.218] INFO: Expecting 41600 events.
[15:41:45.916] INFO: 41600 events read in total (3171ms).
[15:41:45.920] INFO: Test took 4367ms.
[15:41:46.025] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:46.025] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 619731
[15:41:46.025] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:41:46.025] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:46.075] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:46.740] INFO: Expecting 41600 events.
[15:41:50.443] INFO: 41600 events read in total (3176ms).
[15:41:50.447] INFO: Test took 4373ms.
[15:41:50.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:50.552] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 619282
[15:41:50.552] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:41:50.552] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:50.601] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:51.266] INFO: Expecting 41600 events.
[15:41:54.960] INFO: 41600 events read in total (3167ms).
[15:41:54.964] INFO: Test took 4363ms.
[15:41:55.070] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:55.070] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 618773
[15:41:55.070] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:41:55.070] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:55.121] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:55.788] INFO: Expecting 41600 events.
[15:41:59.505] INFO: 41600 events read in total (3190ms).
[15:41:59.509] INFO: Test took 4388ms.
[15:41:59.615] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:59.615] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 620147
[15:41:59.615] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:41:59.615] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:59.664] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:42:00.329] INFO: Expecting 41600 events.
[15:42:04.024] INFO: 41600 events read in total (3168ms).
[15:42:04.028] INFO: Test took 4364ms.
[15:42:04.137] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:04.137] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 619997
[15:42:04.137] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:42:04.137] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:04.188] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:42:04.856] INFO: Expecting 41600 events.
[15:42:08.548] INFO: 41600 events read in total (3166ms).
[15:42:08.552] INFO: Test took 4364ms.
[15:42:08.659] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:08.659] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 620084
[15:42:08.659] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:42:08.659] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:08.710] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:42:09.377] INFO: Expecting 41600 events.
[15:42:13.081] INFO: 41600 events read in total (3177ms).
[15:42:13.086] INFO: Test took 4376ms.
[15:42:13.195] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:13.195] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 621030
[15:42:13.195] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:42:13.195] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:13.245] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:42:13.915] INFO: Expecting 41600 events.
[15:42:17.620] INFO: 41600 events read in total (3178ms).
[15:42:17.624] INFO: Test took 4379ms.
[15:42:17.766] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:17.767] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 618926
[15:42:17.767] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:42:17.767] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:17.819] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:42:18.486] INFO: Expecting 41600 events.
[15:42:22.186] INFO: 41600 events read in total (3173ms).
[15:42:22.191] INFO: Test took 4372ms.
[15:42:22.295] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:22.295] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 619513
[15:42:22.295] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:42:22.295] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:22.345] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:42:23.013] INFO: Expecting 41600 events.
[15:42:26.713] INFO: 41600 events read in total (3174ms).
[15:42:26.717] INFO: Test took 4372ms.
[15:42:26.821] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:26.821] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 618311
[15:42:26.821] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:42:26.821] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:26.870] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:42:27.535] INFO: Expecting 41600 events.
[15:42:31.235] INFO: 41600 events read in total (3173ms).
[15:42:31.239] INFO: Test took 4369ms.
[15:42:31.343] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:31.343] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 620126
[15:42:31.343] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:42:31.343] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:31.393] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:42:32.058] INFO: Expecting 41600 events.
[15:42:35.756] INFO: 41600 events read in total (3171ms).
[15:42:35.760] INFO: Test took 4367ms.
[15:42:35.867] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:35.867] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 620234
[15:42:35.867] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:42:35.868] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:35.919] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:42:36.586] INFO: Expecting 41600 events.
[15:42:40.256] INFO: 41600 events read in total (3143ms).
[15:42:40.260] INFO: Test took 4341ms.
[15:42:40.366] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:40.366] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 617783
[15:42:40.366] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:42:40.366] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:40.419] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:42:41.086] INFO: Expecting 41600 events.
[15:42:44.628] INFO: 41600 events read in total (3015ms).
[15:42:44.632] INFO: Test took 4213ms.
[15:42:44.736] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:44.736] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 615427
[15:42:44.736] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:42:44.737] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:45.010] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 172 eff = 0.999255
[15:42:45.010] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 153 eff = 0.999567
[15:42:45.010] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 171 eff = 0.998918
[15:42:45.011] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 165 eff = 0.998293
[15:42:45.011] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 168 eff = 0.998245
[15:42:45.011] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 163 eff = 0.997933
[15:42:45.011] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 159 eff = 0.998293
[15:42:45.011] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 161 eff = 0.998438
[15:42:45.012] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 171 eff = 0.998413
[15:42:45.012] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 159 eff = 0.997668
[15:42:45.012] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 151 eff = 0.997957
[15:42:45.012] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 158 eff = 0.998269
[15:42:45.012] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 162 eff = 0.997837
[15:42:45.012] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 179 eff = 0.998822
[15:42:45.013] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 123 eff = 0.999567
[15:42:45.013] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 173 eff = 0.999279
[15:44:00.417] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:44:00.418] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:44:00.418] INFO: ----------------------------------------------------------------------
[15:44:00.418] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:44:00.418] INFO: ----------------------------------------------------------------------
[15:44:00.418] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:44:00.418] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:44:00.418] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:44:00.418] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:44:00.418] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:44:00.418] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:44:00.418] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:44:00.418] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:44:00.418] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:44:00.418] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:44:00.418] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:44:00.418] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:44:00.435] INFO: ROC 5 masking pixel 3/4
[15:44:00.436] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:44:01.108] INFO: Expecting 208000 events.
[15:44:15.712] INFO: 208000 events read in total (14077ms).
[15:44:15.727] INFO: Test took 15291ms.
[15:44:16.277] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:16.277] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2808731
[15:44:16.277] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:44:16.277] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:44:16.700] INFO: number of dead pixels (per ROC): 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[15:44:16.700] INFO: number of red-efficiency pixels: 170 151 243 333 452 394 357 383 311 447 443 403 438 268 123 158
[15:44:16.700] INFO: number of X-ray hits detected: 128592 83374 136309 204504 214060 210832 207098 184119 183268 220882 215957 205177 213998 143159 83432 107411
[15:44:16.700] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:44:16.700] INFO: number of Vcal hits detected: 207826 207840 207739 207640 207505 207527 207617 207592 207672 207508 207522 207524 207531 207702 207871 207834
[15:44:16.700] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[15:44:16.700] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[15:44:16.700] INFO: X-ray hit rate [MHz/cm2]: 37.7 24.4 40.0 59.9 62.7 61.8 60.7 54.0 53.7 64.7 63.3 60.1 62.7 42.0 24.5 31.5
[15:44:16.700] INFO: PixTestHighRate::doXPixelAlive() done
[15:44:16.748] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:44:16.748] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:44:16.748] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:44:16.748] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:44:16.748] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:44:16.748] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:44:16.749] INFO: PixTest:: pg_setup set to default.
[15:45:01.954] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:45:01.954] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[15:45:05.042] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:45:05.042] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 377mA
[15:45:05.043] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[15:45:05.043] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[15:45:05.043] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:45:05.043] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:45:05.043] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:45:05.043] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:45:05.043] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:45:05.044] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:45:05.044] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:45:05.044] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:45:05.044] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:45:05.044] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:45:05.133] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:45:05.133] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:45:05.142] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:45:05.142] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:45:05.142] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:45:05.142] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:45:05.142] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:45:05.142] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:45:05.144] QUIET: Connection to board 58 closed.
[15:45:05.223] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[15:36:47.919] INFO: *** Welcome to pxar ***
[15:36:47.919] INFO: *** Today: 2016/10/05
[15:36:47.946] INFO: *** Version: v1.9.0-818-g96727
[15:36:47.946] INFO: readRocDacs: data/mp601/dacParameters35_C0.dat .. data/mp601/dacParameters35_C15.dat
[15:36:47.947] INFO: readTbmDacs: data/mp601/tbmParameters_C0a.dat .. data/mp601/tbmParameters_C0b.dat
[15:36:47.947] INFO: readMaskFile: data/mp601/defaultMaskFile.dat
[15:36:47.947] INFO: MASKED Roc 5 col/row: 3 4
[15:36:47.947] INFO: readTrimFile: data/mp601/trimParameters35_C0.dat .. data/mp601/trimParameters35_C15.dat
[15:36:47.994] INFO: masking Roc 5 col/row: 3 4
[15:36:48.097] INFO: clk: 4
[15:36:48.097] INFO: ctr: 4
[15:36:48.097] INFO: sda: 19
[15:36:48.097] INFO: tin: 9
[15:36:48.097] INFO: level: 15
[15:36:48.097] INFO: triggerdelay: 0
[15:36:48.097] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:36:48.097] INFO: Log level: DEBUG
[15:36:48.108] QUIET: Connection to board DTB_WRPRHI opened.
[15:36:48.111] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:36:48.114] INFO: RPC call hashes of host and DTB match: 398089610
[15:36:49.716] INFO: DUT info:
[15:36:49.716] INFO: The DUT currently contains the following objects:
[15:36:49.716] INFO: 2 TBM Cores tbm08c (2 ON)
[15:36:49.716] INFO: TBM Core alpha (0): 7 registers set
[15:36:49.716] INFO: TBM Core beta (1): 7 registers set
[15:36:49.717] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:36:49.717] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:49.717] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:49.717] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:49.717] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:49.717] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:49.717] INFO: ROC 5: 19 DACs set, Pixels: 1 masked, 0 active.
[15:36:49.717] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:49.717] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:49.717] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:49.717] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:49.717] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:49.717] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:49.717] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:49.717] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:49.717] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:49.717] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:49.717] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:36:49.717] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:36:49.717] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:36:49.717] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:36:49.717] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:36:49.717] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:36:49.718] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:36:49.719] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:36:49.720] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:36:49.723] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31330304
[15:36:49.723] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xffb0e0
[15:36:49.723] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xd9d370
[15:36:49.723] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f9ca5d94010
[15:36:49.723] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f9cabfff510
[15:36:49.723] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31338496 fPxarMemory = 0x7f9ca5d94010
[15:36:49.724] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 373mA
[15:36:49.725] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 469.5mA
[15:36:49.725] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[15:36:50.224] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x13e9fd0
[15:36:50.290] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:36:50.290] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:36:50.290] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:36:50.296] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:36:50.296] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:36:50.296] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:36:50.305] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:36:50.305] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:36:50.322] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:36:50.322] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:36:50.338] INFO: PixTestCmd::init()
[15:36:50.344] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:36:50.344] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:36:50.344] INFO: readGainPedestalParameters data/mp601/phCalibrationFitErr35_C0.dat .. data/mp601/phCalibrationFitErr35_C15.dat
[15:36:50.595] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:36:50.595] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:36:50.610] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:36:50.616] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:36:50.616] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:36:50.616] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:36:50.641] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:36:50.641] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:36:50.641] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:36:50.641] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:36:50.641] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:36:50.648] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:36:50.648] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:36:50.669] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:36:50.669] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:36:50.669] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:36:50.669] INFO: readReadbackCal: data/mp601/readbackCal_C0.dat .. data/mp601/readbackCal_C15.dat
[15:36:50.682] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:36:50.693] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:36:50.693] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:36:50.694] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:36:50.694] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:36:50.714] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:36:50.715] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:36:53.293] INFO: ######################################################################
[15:36:53.293] INFO: PixTestAlive::doTest()
[15:36:53.293] INFO: ######################################################################
[15:36:53.296] INFO: ----------------------------------------------------------------------
[15:36:53.296] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:36:53.296] INFO: ----------------------------------------------------------------------
[15:36:53.299] INFO: ROC 5 masking pixel 3/4
[15:36:53.299] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:36:53.677] INFO: Expecting 41600 events.
[15:36:58.065] INFO: 41600 events read in total (3669ms).
[15:36:58.214] INFO: Test took 4915ms.
[15:36:58.227] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:58.227] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:36:58.227] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:36:58.507] INFO: PixTestAlive::aliveTest() done
[15:36:58.507] INFO: number of dead pixels (per ROC): 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[15:36:58.507] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 32 24 42 77 65 75 72 53 57 68 62 69 81 60 31 26
[15:36:58.507] INFO: ROC 5 masking pixel 3/4
[15:36:58.532] INFO: ----------------------------------------------------------------------
[15:36:58.532] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:36:58.532] INFO: ----------------------------------------------------------------------
[15:36:58.534] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:36:58.912] INFO: Expecting 41600 events.
[15:37:02.089] INFO: 41600 events read in total (2462ms).
[15:37:02.090] INFO: Test took 3556ms.
[15:37:02.090] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:02.090] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:37:02.090] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:37:02.090] INFO: ROC 5 masking pixel 3/4
[15:37:02.090] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:37:02.512] INFO: PixTestAlive::maskTest() done
[15:37:02.512] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:37:02.512] INFO: ROC 5 masking pixel 3/4
[15:37:02.535] INFO: ----------------------------------------------------------------------
[15:37:02.535] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:37:02.535] INFO: ----------------------------------------------------------------------
[15:37:02.538] INFO: ROC 5 masking pixel 3/4
[15:37:02.538] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:02.915] INFO: Expecting 41600 events.
[15:37:07.295] INFO: 41600 events read in total (3665ms).
[15:37:07.297] INFO: Test took 4759ms.
[15:37:07.308] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:07.308] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[15:37:07.308] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:37:07.679] INFO: PixTestAlive::addressDecodingTest() done
[15:37:07.679] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:37:07.680] INFO: ROC 5 masking pixel 3/4
[15:37:07.680] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:37:22.041] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:37:22.042] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:37:22.042] INFO: ----------------------------------------------------------------------
[15:37:22.042] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:37:22.042] INFO: ----------------------------------------------------------------------
[15:37:22.181] INFO: Expecting 768 events.
[15:37:23.315] INFO: 768 events read in total (419ms).
[15:37:23.315] INFO: Test took 1268ms.
[15:37:23.318] INFO: ROC 5 masking pixel 3/4
[15:37:23.321] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:24.118] INFO: Expecting 41600 events.
[15:37:27.888] INFO: 41600 events read in total (3243ms).
[15:37:27.894] INFO: Test took 4573ms.
[15:37:28.037] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:28.037] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 800496
[15:37:28.037] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:37:28.037] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:28.098] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:28.765] INFO: Expecting 41600 events.
[15:37:32.635] INFO: 41600 events read in total (3343ms).
[15:37:32.640] INFO: Test took 4542ms.
[15:37:32.774] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:32.774] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 809105
[15:37:32.774] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:37:32.774] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:32.835] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:33.501] INFO: Expecting 41600 events.
[15:37:37.401] INFO: 41600 events read in total (3374ms).
[15:37:37.406] INFO: Test took 4571ms.
[15:37:37.537] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:37.538] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 809617
[15:37:37.538] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:37:37.538] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:37.600] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:38.265] INFO: Expecting 41600 events.
[15:37:42.182] INFO: 41600 events read in total (3391ms).
[15:37:42.187] INFO: Test took 4587ms.
[15:37:42.318] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:42.318] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 810844
[15:37:42.318] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:37:42.319] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:42.381] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:43.047] INFO: Expecting 41600 events.
[15:37:46.960] INFO: 41600 events read in total (3386ms).
[15:37:46.965] INFO: Test took 4584ms.
[15:37:47.100] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:47.100] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 812167
[15:37:47.100] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:37:47.100] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:47.164] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:47.831] INFO: Expecting 41600 events.
[15:37:51.749] INFO: 41600 events read in total (3392ms).
[15:37:51.754] INFO: Test took 4590ms.
[15:37:51.886] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:51.886] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 812602
[15:37:51.886] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:37:51.887] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:51.949] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:52.614] INFO: Expecting 41600 events.
[15:37:56.542] INFO: 41600 events read in total (3401ms).
[15:37:56.547] INFO: Test took 4598ms.
[15:37:56.682] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:56.682] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 812364
[15:37:56.683] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:37:56.683] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:37:56.745] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:57.410] INFO: Expecting 41600 events.
[15:38:01.353] INFO: 41600 events read in total (3416ms).
[15:38:01.358] INFO: Test took 4613ms.
[15:38:01.499] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:01.499] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 812034
[15:38:01.499] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:38:01.500] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:01.564] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:02.231] INFO: Expecting 41600 events.
[15:38:06.163] INFO: 41600 events read in total (3405ms).
[15:38:06.168] INFO: Test took 4604ms.
[15:38:06.300] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:06.300] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 812648
[15:38:06.300] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:38:06.300] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:06.362] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:07.027] INFO: Expecting 41600 events.
[15:38:10.967] INFO: 41600 events read in total (3413ms).
[15:38:10.972] INFO: Test took 4610ms.
[15:38:11.104] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:11.104] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 812962
[15:38:11.104] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:38:11.105] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:11.167] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:11.832] INFO: Expecting 41600 events.
[15:38:15.769] INFO: 41600 events read in total (3410ms).
[15:38:15.774] INFO: Test took 4607ms.
[15:38:15.907] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:15.907] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 812082
[15:38:15.907] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:38:15.908] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:15.970] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:16.635] INFO: Expecting 41600 events.
[15:38:20.557] INFO: 41600 events read in total (3395ms).
[15:38:20.562] INFO: Test took 4592ms.
[15:38:20.696] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:20.696] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 813804
[15:38:20.696] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:38:20.697] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:20.761] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:21.428] INFO: Expecting 41600 events.
[15:38:25.379] INFO: 41600 events read in total (3425ms).
[15:38:25.384] INFO: Test took 4623ms.
[15:38:25.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:25.521] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 812234
[15:38:25.521] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:38:25.521] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:25.585] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:26.252] INFO: Expecting 41600 events.
[15:38:30.190] INFO: 41600 events read in total (3411ms).
[15:38:30.195] INFO: Test took 4610ms.
[15:38:30.326] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:30.326] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 811606
[15:38:30.326] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:38:30.327] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:30.389] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:31.054] INFO: Expecting 41600 events.
[15:38:34.989] INFO: 41600 events read in total (3408ms).
[15:38:34.994] INFO: Test took 4605ms.
[15:38:35.129] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:35.129] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 811993
[15:38:35.129] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:38:35.129] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:35.190] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:35.855] INFO: Expecting 41600 events.
[15:38:39.782] INFO: 41600 events read in total (3401ms).
[15:38:39.788] INFO: Test took 4598ms.
[15:38:39.919] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:39.919] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 812964
[15:38:39.919] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:38:39.919] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:39.981] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:40.646] INFO: Expecting 41600 events.
[15:38:44.583] INFO: 41600 events read in total (3410ms).
[15:38:44.588] INFO: Test took 4607ms.
[15:38:44.724] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:44.724] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 814227
[15:38:44.724] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:38:44.724] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:44.788] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:45.455] INFO: Expecting 41600 events.
[15:38:49.385] INFO: 41600 events read in total (3403ms).
[15:38:49.390] INFO: Test took 4602ms.
[15:38:49.523] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:49.523] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 813326
[15:38:49.523] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:38:49.523] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:49.587] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:50.252] INFO: Expecting 41600 events.
[15:38:54.146] INFO: 41600 events read in total (3367ms).
[15:38:54.151] INFO: Test took 4564ms.
[15:38:54.285] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:54.285] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 811243
[15:38:54.285] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:38:54.285] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:54.350] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:55.017] INFO: Expecting 41600 events.
[15:38:58.732] INFO: 41600 events read in total (3189ms).
[15:38:58.737] INFO: Test took 4387ms.
[15:38:58.870] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:58.871] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 805210
[15:38:58.871] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:38:58.871] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:59.153] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 170 eff = 0.998822
[15:38:59.154] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 160 eff = 0.999111
[15:38:59.154] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 173 eff = 0.997957
[15:38:59.154] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 162 eff = 0.996755
[15:38:59.154] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 171 eff = 0.997067
[15:38:59.155] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 164 eff = 0.996226
[15:38:59.155] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 163 eff = 0.996394
[15:38:59.155] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 164 eff = 0.996779
[15:38:59.155] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 170 eff = 0.997452
[15:38:59.156] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 155 eff = 0.996106
[15:38:59.156] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 154 eff = 0.996082
[15:38:59.156] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 155 eff = 0.996611
[15:38:59.156] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 165 eff = 0.995962
[15:38:59.157] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 173 eff = 0.997548
[15:38:59.157] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 131 eff = 0.999159
[15:38:59.157] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 164 eff = 0.999014
[15:39:35.001] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:39:35.002] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:39:35.002] INFO: ----------------------------------------------------------------------
[15:39:35.002] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:39:35.002] INFO: ----------------------------------------------------------------------
[15:39:35.002] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:39:35.002] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:39:35.002] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:39:35.002] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:39:35.002] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:39:35.002] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:39:35.002] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:39:35.002] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:39:35.002] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:39:35.002] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:39:35.002] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:39:35.002] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:39:35.019] INFO: ROC 5 masking pixel 3/4
[15:39:35.020] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:39:35.687] INFO: Expecting 208000 events.
[15:39:51.956] INFO: 208000 events read in total (15742ms).
[15:39:51.975] INFO: Test took 16955ms.
[15:39:52.688] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:52.688] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3757376
[15:39:52.688] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:39:52.688] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:39:53.171] INFO: number of dead pixels (per ROC): 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[15:39:53.171] INFO: number of red-efficiency pixels: 295 229 375 623 695 692 692 551 598 813 644 639 769 465 210 258
[15:39:53.171] INFO: number of X-ray hits detected: 173384 112757 183632 274292 287450 285397 277280 247653 247896 296555 291178 275445 288995 192345 112533 144025
[15:39:53.171] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:39:53.171] INFO: number of Vcal hits detected: 207685 207758 207583 207313 207200 207162 207083 207375 207339 207077 207270 207265 207073 207453 207779 207721
[15:39:53.171] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.7 99.6 99.7 99.7 99.6 99.7 99.7 99.6 99.8 99.9 99.9
[15:39:53.171] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.7 99.6 99.6 99.6 99.7 99.7 99.6 99.6 99.6 99.6 99.7 99.9 99.9
[15:39:53.171] INFO: X-ray hit rate [MHz/cm2]: 50.8 33.0 53.8 80.4 84.3 83.7 81.3 72.6 72.7 86.9 85.3 80.7 84.7 56.4 33.0 42.2
[15:39:53.171] INFO: PixTestHighRate::doXPixelAlive() done
[15:39:53.216] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:39:53.216] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:39:53.216] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:39:53.216] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:39:53.216] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:39:53.216] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:39:53.216] INFO: PixTest:: pg_setup set to default.
[15:40:28.633] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:40:28.634] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[15:40:32.442] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:40:32.442] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 377mA
[15:40:32.443] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[15:40:32.443] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:40:32.443] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:40:32.443] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:40:32.443] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:40:32.443] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:40:32.444] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:40:32.444] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:40:32.444] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:40:32.444] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:40:32.444] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:40:32.444] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:40:32.538] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:40:32.538] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:40:32.547] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:40:32.547] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:40:32.547] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:40:32.547] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:40:32.547] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:40:32.547] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:40:32.550] QUIET: Connection to board 58 closed.
[15:40:32.629] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-904-gd94b9f2 on branch master