Test Date: 1973-11-29 16:33
Analysis date: 2016-06-02 17:14
Logfile
hrData_40.log
[17:53:20.207] INFO: *** Welcome to pxar ***
[17:53:20.207] INFO: *** Today: 2016/06/01
[17:53:20.229] INFO: *** Version: v1.9.0-805-geb36
[17:53:20.229] INFO: readRocDacs: data/mi302/dacParameters35_C0.dat .. data/mi302/dacParameters35_C15.dat
[17:53:20.230] INFO: readTbmDacs: data/mi302/tbmParameters_C0a.dat .. data/mi302/tbmParameters_C0b.dat
[17:53:20.231] INFO: readMaskFile: data/mi302/defaultMaskFile.dat
[17:53:20.231] INFO: MASKED Roc 4 col/row: 21 60
[17:53:20.231] INFO: MASKED Roc 5 col/row: 19 50
[17:53:20.231] INFO: MASKED Roc 5 col/row: 47 58
[17:53:20.231] INFO: MASKED Roc 9 col/row: 9 50
[17:53:20.231] INFO: MASKED Roc 14 col/row: 45 29
[17:53:20.231] INFO: readTrimFile: data/mi302/trimParameters35_C0.dat .. data/mi302/trimParameters35_C15.dat
[17:53:20.268] INFO: masking Roc 4 col/row: 21 60
[17:53:20.278] INFO: masking Roc 5 col/row: 19 50
[17:53:20.278] INFO: masking Roc 5 col/row: 47 58
[17:53:20.316] INFO: masking Roc 9 col/row: 9 50
[17:53:20.362] INFO: masking Roc 14 col/row: 45 29
[17:53:20.381] INFO: clk: 4
[17:53:20.381] INFO: ctr: 4
[17:53:20.381] INFO: sda: 19
[17:53:20.381] INFO: tin: 9
[17:53:20.381] INFO: level: 15
[17:53:20.381] INFO: triggerdelay: 0
[17:53:20.381] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:53:20.381] INFO: Log level: DEBUG
[17:53:20.392] QUIET: Connection to board DTB_WRPRHI opened.
[17:53:20.395] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[17:53:20.398] INFO: RPC call hashes of host and DTB match: 398089610
[17:53:21.997] INFO: DUT info:
[17:53:21.997] INFO: The DUT currently contains the following objects:
[17:53:21.997] INFO: 2 TBM Cores tbm08c (2 ON)
[17:53:21.997] INFO: TBM Core alpha (0): 7 registers set
[17:53:21.997] INFO: TBM Core beta (1): 7 registers set
[17:53:21.997] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:53:21.997] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:21.997] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:21.997] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:21.997] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:21.997] INFO: ROC 4: 19 DACs set, Pixels: 1 masked, 0 active.
[17:53:21.997] INFO: ROC 5: 19 DACs set, Pixels: 2 masked, 0 active.
[17:53:21.997] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:21.997] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:21.997] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:21.997] INFO: ROC 9: 19 DACs set, Pixels: 1 masked, 0 active.
[17:53:21.998] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:21.998] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:21.998] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:21.998] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:21.998] INFO: ROC 14: 19 DACs set, Pixels: 1 masked, 0 active.
[17:53:21.998] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:53:21.998] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:53:21.999] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:53:21.000] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:53:22.000] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:53:22.002] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31367168
[17:53:22.002] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x12d1bd0
[17:53:22.002] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1073e60
[17:53:22.002] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f0435d94010
[17:53:22.002] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f043bfff510
[17:53:22.002] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31375360 fPxarMemory = 0x7f0435d94010
[17:53:22.004] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 386.7mA
[17:53:22.005] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 471.9mA
[17:53:22.005] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[17:53:22.492] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x16bc860
[17:53:22.554] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:53:22.554] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:53:22.554] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:53:22.560] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:53:22.560] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:53:22.560] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:53:22.569] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:53:22.569] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:53:22.589] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:53:22.589] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:53:22.606] INFO: PixTestCmd::init()
[17:53:22.614] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:53:22.614] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:53:22.614] INFO: readGainPedestalParameters data/mi302/phCalibrationFitErr35_C0.dat .. data/mi302/phCalibrationFitErr35_C15.dat
[17:53:22.863] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:53:22.863] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:53:22.877] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:53:22.883] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:53:22.883] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:53:22.883] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:53:22.906] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:53:22.906] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:53:22.906] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:53:22.906] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:53:22.906] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:53:22.912] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:53:22.912] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:53:22.926] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:53:22.926] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:53:22.926] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:53:22.926] INFO: readReadbackCal: data/mi302/readbackCal_C0.dat .. data/mi302/readbackCal_C15.dat
[17:53:22.936] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:53:22.944] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:53:22.944] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:53:22.944] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:53:22.944] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:53:22.958] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:53:22.958] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:53:28.338] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[17:53:29.946] INFO: ######################################################################
[17:53:29.946] INFO: PixTestXray::doTest()
[17:53:29.946] INFO: ######################################################################
[17:53:29.946] INFO: ----------------------------------------------------------------------
[17:53:29.946] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[17:53:29.946] INFO: ----------------------------------------------------------------------
[17:53:29.950] INFO: ROC 4 masking pixel 21/60
[17:53:29.950] INFO: ROC 5 masking pixel 19/50
[17:53:29.950] INFO: ROC 5 masking pixel 47/58
[17:53:29.950] INFO: ROC 9 masking pixel 9/50
[17:53:29.950] INFO: ROC 14 masking pixel 45/29
[17:53:30.919] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[17:53:42.027] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[17:53:42.031] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:54:06.243] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1094475 events.
[17:54:13.874] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1094475, pixels seen in all events: 7118760
[17:54:14.144] INFO: Resuming triggers.
[17:54:25.256] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[17:54:25.285] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:54:49.175] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1094732 events.
[17:54:56.829] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1094732, pixels seen in all events: 7114494
[17:54:57.093] INFO: Resuming triggers.
[17:55:08.210] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[17:55:08.238] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:55:32.220] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1095187 events.
[17:55:39.961] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1095187, pixels seen in all events: 7109133
[17:55:40.132] INFO: Resuming triggers.
[17:55:51.248] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[17:55:51.277] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:56:15.205] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1095140 events.
[17:56:22.821] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1095140, pixels seen in all events: 7109459
[17:56:23.084] INFO: Resuming triggers.
[17:56:34.200] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[17:56:34.229] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:56:58.201] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 1095037 events.
[17:57:05.926] DEBUG: <PixTestXray.cc/processData:L809> # events read: 1095037, pixels seen in all events: 7111224
[17:57:06.008] INFO: Resuming triggers.
[17:57:10.515] INFO: data taking finished, elapsed time: 60 seconds.
[17:57:10.711] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:57:20.483] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 443994 events.
[17:57:23.575] DEBUG: <PixTestXray.cc/processData:L809> # events read: 443994, pixels seen in all events: 2882476
[17:57:23.672] INFO: PixTest:: pg_setup set to default.
[17:57:23.711] INFO: PixTestXray::doPhRun() done
[17:57:23.711] INFO: PixTestXray::doTest() done
[17:57:35.890] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:57:35.891] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[17:57:35.892] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 472.7mA
[17:57:35.892] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[17:57:35.892] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:57:35.892] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:57:35.892] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:57:35.892] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:57:35.892] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:57:35.892] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:57:35.892] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:57:35.892] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:57:35.892] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:57:35.892] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:57:35.893] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:57:35.893] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:57:35.893] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:57:35.893] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:57:35.893] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:57:35.893] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:57:35.893] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:57:35.893] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:57:36.172] QUIET: Connection to board 58 closed.
[17:57:36.252] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[17:46:24.883] INFO: *** Welcome to pxar ***
[17:46:24.883] INFO: *** Today: 2016/06/01
[17:46:24.905] INFO: *** Version: v1.9.0-805-geb36
[17:46:24.905] INFO: readRocDacs: data/mi302/dacParameters35_C0.dat .. data/mi302/dacParameters35_C15.dat
[17:46:24.906] INFO: readTbmDacs: data/mi302/tbmParameters_C0a.dat .. data/mi302/tbmParameters_C0b.dat
[17:46:24.906] INFO: readMaskFile: data/mi302/defaultMaskFile.dat
[17:46:24.906] INFO: MASKED Roc 4 col/row: 21 60
[17:46:24.906] INFO: MASKED Roc 5 col/row: 19 50
[17:46:24.906] INFO: MASKED Roc 5 col/row: 47 58
[17:46:24.907] INFO: MASKED Roc 9 col/row: 9 50
[17:46:24.907] INFO: MASKED Roc 14 col/row: 45 29
[17:46:24.907] INFO: readTrimFile: data/mi302/trimParameters35_C0.dat .. data/mi302/trimParameters35_C15.dat
[17:46:24.945] INFO: masking Roc 4 col/row: 21 60
[17:46:24.955] INFO: masking Roc 5 col/row: 19 50
[17:46:24.955] INFO: masking Roc 5 col/row: 47 58
[17:46:24.993] INFO: masking Roc 9 col/row: 9 50
[17:46:25.041] INFO: masking Roc 14 col/row: 45 29
[17:46:25.060] INFO: clk: 4
[17:46:25.060] INFO: ctr: 4
[17:46:25.060] INFO: sda: 19
[17:46:25.060] INFO: tin: 9
[17:46:25.060] INFO: level: 15
[17:46:25.060] INFO: triggerdelay: 0
[17:46:25.060] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:46:25.060] INFO: Log level: DEBUG
[17:46:25.072] QUIET: Connection to board DTB_WRPRHI opened.
[17:46:25.075] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[17:46:25.078] INFO: RPC call hashes of host and DTB match: 398089610
[17:46:26.678] INFO: DUT info:
[17:46:26.678] INFO: The DUT currently contains the following objects:
[17:46:26.678] INFO: 2 TBM Cores tbm08c (2 ON)
[17:46:26.678] INFO: TBM Core alpha (0): 7 registers set
[17:46:26.678] INFO: TBM Core beta (1): 7 registers set
[17:46:26.678] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:46:26.678] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:26.678] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:26.678] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:26.678] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:26.678] INFO: ROC 4: 19 DACs set, Pixels: 1 masked, 0 active.
[17:46:26.678] INFO: ROC 5: 19 DACs set, Pixels: 2 masked, 0 active.
[17:46:26.678] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:26.678] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:26.678] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:26.678] INFO: ROC 9: 19 DACs set, Pixels: 1 masked, 0 active.
[17:46:26.678] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:26.678] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:26.678] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:26.678] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:26.678] INFO: ROC 14: 19 DACs set, Pixels: 1 masked, 0 active.
[17:46:26.678] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:26.678] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:46:26.678] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:46:26.678] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:46:26.678] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:46:26.678] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:46:26.679] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:46:26.680] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:46:26.681] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:46:26.683] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34861056
[17:46:26.683] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xc9bbd0
[17:46:26.683] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xa3de60
[17:46:26.683] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fa4c9d94010
[17:46:26.683] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fa4cffff510
[17:46:26.683] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34869248 fPxarMemory = 0x7fa4c9d94010
[17:46:26.684] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 386.7mA
[17:46:26.685] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 471.9mA
[17:46:26.686] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[17:46:27.174] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1086860
[17:46:27.233] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:46:27.233] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:46:27.233] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:46:27.239] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:46:27.239] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:46:27.239] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:46:27.250] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:46:27.250] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:46:27.262] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:46:27.262] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:46:27.275] INFO: PixTestCmd::init()
[17:46:27.280] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:46:27.280] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:46:27.280] INFO: readGainPedestalParameters data/mi302/phCalibrationFitErr35_C0.dat .. data/mi302/phCalibrationFitErr35_C15.dat
[17:46:27.523] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:46:27.523] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:46:27.537] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:46:27.542] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:46:27.542] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:46:27.543] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:46:27.567] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:46:27.567] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:46:27.567] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:46:27.567] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:46:27.567] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:46:27.573] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:46:27.573] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:46:27.587] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:46:27.587] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:46:27.587] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:46:27.587] INFO: readReadbackCal: data/mi302/readbackCal_C0.dat .. data/mi302/readbackCal_C15.dat
[17:46:27.600] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:46:27.607] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:46:27.608] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:46:27.608] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:46:27.608] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:46:27.625] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:46:27.625] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:46:32.274] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[17:46:33.858] INFO: ######################################################################
[17:46:33.858] INFO: PixTestXray::doTest()
[17:46:33.858] INFO: ######################################################################
[17:46:33.858] INFO: ----------------------------------------------------------------------
[17:46:33.858] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[17:46:33.858] INFO: ----------------------------------------------------------------------
[17:46:33.862] INFO: ROC 4 masking pixel 21/60
[17:46:33.862] INFO: ROC 5 masking pixel 19/50
[17:46:33.862] INFO: ROC 5 masking pixel 47/58
[17:46:33.862] INFO: ROC 9 masking pixel 9/50
[17:46:33.862] INFO: ROC 14 masking pixel 45/29
[17:46:34.831] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[17:46:41.975] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[17:46:41.979] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:47:04.503] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 703878 events.
[17:47:17.933] DEBUG: <PixTestXray.cc/processData:L809> # events read: 703878, pixels seen in all events: 11807353
[17:47:18.139] INFO: Resuming triggers.
[17:47:25.286] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[17:47:25.314] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:47:47.756] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 704096 events.
[17:48:01.188] DEBUG: <PixTestXray.cc/processData:L809> # events read: 704096, pixels seen in all events: 11802735
[17:48:01.376] INFO: Resuming triggers.
[17:48:08.523] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[17:48:08.552] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:48:31.207] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 704023 events.
[17:48:43.887] DEBUG: <PixTestXray.cc/processData:L809> # events read: 704023, pixels seen in all events: 11804155
[17:48:44.075] INFO: Resuming triggers.
[17:48:51.222] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[17:48:51.251] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:49:13.686] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 704024 events.
[17:49:26.886] DEBUG: <PixTestXray.cc/processData:L809> # events read: 704024, pixels seen in all events: 11803247
[17:49:27.078] INFO: Resuming triggers.
[17:49:34.226] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[17:49:34.258] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:49:56.780] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 704122 events.
[17:50:08.483] DEBUG: <PixTestXray.cc/processData:L809> # events read: 704122, pixels seen in all events: 11802611
[17:50:08.671] INFO: Resuming triggers.
[17:50:15.818] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[17:50:15.847] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:50:38.322] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 704085 events.
[17:50:50.853] DEBUG: <PixTestXray.cc/processData:L809> # events read: 704085, pixels seen in all events: 11802636
[17:50:51.041] INFO: Resuming triggers.
[17:50:58.189] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[17:50:58.217] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:51:20.668] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 704171 events.
[17:51:32.564] DEBUG: <PixTestXray.cc/processData:L809> # events read: 704171, pixels seen in all events: 11801658
[17:51:32.703] INFO: Resuming triggers.
[17:51:39.854] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[17:51:39.930] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:52:02.321] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 704417 events.
[17:52:14.833] DEBUG: <PixTestXray.cc/processData:L809> # events read: 704417, pixels seen in all events: 11798953
[17:52:15.018] INFO: Resuming triggers.
[17:52:17.920] INFO: data taking finished, elapsed time: 60 seconds.
[17:52:18.117] DEBUG: <PixTestXray.cc/processData:L740> Getting Event Buffer
[17:52:27.433] DEBUG: <PixTestXray.cc/processData:L758> Processing Data: 285927 events.
[17:52:32.246] DEBUG: <PixTestXray.cc/processData:L809> # events read: 285927, pixels seen in all events: 4789250
[17:52:32.321] INFO: PixTest:: pg_setup set to default.
[17:52:32.361] INFO: PixTestXray::doPhRun() done
[17:52:32.361] INFO: PixTestXray::doTest() done
[17:52:46.530] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:52:46.531] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[17:52:46.532] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 473.5mA
[17:52:46.532] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[17:52:46.532] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:52:46.532] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:52:46.532] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:52:46.532] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:52:46.532] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:52:46.532] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:52:46.532] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:52:46.532] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:52:46.532] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:52:46.533] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:52:46.533] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:52:46.533] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:52:46.533] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:52:46.533] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:52:46.533] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:52:46.533] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:52:46.533] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:52:46.533] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:52:46.845] QUIET: Connection to board 58 closed.
[17:52:46.925] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[17:40:00.654] INFO: *** Welcome to pxar ***
[17:40:00.654] INFO: *** Today: 2016/06/01
[17:40:00.676] INFO: *** Version: v1.9.0-805-geb36
[17:40:00.676] INFO: readRocDacs: data/mi302/dacParameters35_C0.dat .. data/mi302/dacParameters35_C15.dat
[17:40:00.677] INFO: readTbmDacs: data/mi302/tbmParameters_C0a.dat .. data/mi302/tbmParameters_C0b.dat
[17:40:00.677] INFO: readMaskFile: data/mi302/defaultMaskFile.dat
[17:40:00.678] INFO: MASKED Roc 4 col/row: 21 60
[17:40:00.678] INFO: MASKED Roc 5 col/row: 19 50
[17:40:00.678] INFO: MASKED Roc 5 col/row: 47 58
[17:40:00.678] INFO: MASKED Roc 9 col/row: 9 50
[17:40:00.678] INFO: MASKED Roc 14 col/row: 45 29
[17:40:00.678] INFO: readTrimFile: data/mi302/trimParameters35_C0.dat .. data/mi302/trimParameters35_C15.dat
[17:40:00.715] INFO: masking Roc 4 col/row: 21 60
[17:40:00.725] INFO: masking Roc 5 col/row: 19 50
[17:40:00.725] INFO: masking Roc 5 col/row: 47 58
[17:40:00.763] INFO: masking Roc 9 col/row: 9 50
[17:40:00.810] INFO: masking Roc 14 col/row: 45 29
[17:40:00.829] INFO: clk: 4
[17:40:00.829] INFO: ctr: 4
[17:40:00.829] INFO: sda: 19
[17:40:00.829] INFO: tin: 9
[17:40:00.829] INFO: level: 15
[17:40:00.829] INFO: triggerdelay: 0
[17:40:00.829] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:40:00.829] INFO: Log level: DEBUG
[17:40:00.841] QUIET: Connection to board DTB_WRPRHI opened.
[17:40:00.845] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[17:40:00.847] INFO: RPC call hashes of host and DTB match: 398089610
[17:40:02.447] INFO: DUT info:
[17:40:02.447] INFO: The DUT currently contains the following objects:
[17:40:02.447] INFO: 2 TBM Cores tbm08c (2 ON)
[17:40:02.447] INFO: TBM Core alpha (0): 7 registers set
[17:40:02.447] INFO: TBM Core beta (1): 7 registers set
[17:40:02.447] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:40:02.447] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:40:02.447] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:40:02.447] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:40:02.447] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:40:02.447] INFO: ROC 4: 19 DACs set, Pixels: 1 masked, 0 active.
[17:40:02.447] INFO: ROC 5: 19 DACs set, Pixels: 2 masked, 0 active.
[17:40:02.447] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:40:02.447] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:40:02.447] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:40:02.447] INFO: ROC 9: 19 DACs set, Pixels: 1 masked, 0 active.
[17:40:02.447] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:40:02.447] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:40:02.447] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:40:02.447] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:40:02.447] INFO: ROC 14: 19 DACs set, Pixels: 1 masked, 0 active.
[17:40:02.447] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:40:02.447] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:40:02.447] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:40:02.447] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:40:02.447] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:40:02.447] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:40:02.448] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:40:02.449] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:40:02.450] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:40:02.452] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33460224
[17:40:02.452] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1176bd0
[17:40:02.452] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xf18e60
[17:40:02.452] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7efc69d94010
[17:40:02.452] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7efc6ffff510
[17:40:02.452] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33468416 fPxarMemory = 0x7efc69d94010
[17:40:02.453] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 387.5mA
[17:40:02.454] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 472.7mA
[17:40:02.455] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[17:40:02.944] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1561920
[17:40:03.007] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:40:03.008] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:40:03.008] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:40:03.013] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:40:03.014] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:40:03.014] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:40:03.023] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:40:03.023] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:40:03.037] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:40:03.037] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:40:03.052] INFO: PixTestCmd::init()
[17:40:03.059] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:40:03.059] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:40:03.059] INFO: readGainPedestalParameters data/mi302/phCalibrationFitErr35_C0.dat .. data/mi302/phCalibrationFitErr35_C15.dat
[17:40:03.302] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:40:03.302] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:40:03.316] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:40:03.321] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:40:03.321] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:40:03.321] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:40:03.345] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:40:03.345] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:40:03.345] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:40:03.345] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:40:03.345] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:40:03.351] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:40:03.352] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:40:03.366] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:40:03.366] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:40:03.366] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:40:03.366] INFO: readReadbackCal: data/mi302/readbackCal_C0.dat .. data/mi302/readbackCal_C15.dat
[17:40:03.381] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:40:03.389] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:40:03.389] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:40:03.389] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:40:03.390] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:40:03.410] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:40:03.410] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:40:11.557] INFO: ######################################################################
[17:40:11.557] INFO: PixTestAlive::doTest()
[17:40:11.557] INFO: ######################################################################
[17:40:11.561] INFO: ----------------------------------------------------------------------
[17:40:11.561] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:40:11.561] INFO: ----------------------------------------------------------------------
[17:40:11.563] INFO: ROC 4 masking pixel 21/60
[17:40:11.563] INFO: ROC 5 masking pixel 19/50
[17:40:11.563] INFO: ROC 5 masking pixel 47/58
[17:40:11.563] INFO: ROC 9 masking pixel 9/50
[17:40:11.563] INFO: ROC 14 masking pixel 45/29
[17:40:11.563] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:40:11.939] INFO: Expecting 41600 events.
[17:40:16.193] INFO: 41600 events read in total (3536ms).
[17:40:16.341] INFO: Test took 4778ms.
[17:40:16.350] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:40:16.350] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66555
[17:40:16.350] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[17:40:16.627] INFO: PixTestAlive::aliveTest() done with 2 decoding errors
[17:40:16.627] INFO: number of dead pixels (per ROC): 0 0 0 0 1 2 0 0 0 1 0 0 0 0 1 0
[17:40:16.627] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 15 12 25 35 43 34 38 42 50 37 40 37 37 24 20 27
[17:40:16.627] INFO: ROC 4 masking pixel 21/60
[17:40:16.627] INFO: ROC 5 masking pixel 19/50
[17:40:16.628] INFO: ROC 5 masking pixel 47/58
[17:40:16.628] INFO: ROC 9 masking pixel 9/50
[17:40:16.628] INFO: ROC 14 masking pixel 45/29
[17:40:16.649] INFO: ----------------------------------------------------------------------
[17:40:16.649] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:40:16.650] INFO: ----------------------------------------------------------------------
[17:40:16.652] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:40:17.026] INFO: Expecting 41600 events.
[17:40:20.087] INFO: 41600 events read in total (2346ms).
[17:40:20.087] INFO: Test took 3435ms.
[17:40:20.087] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:40:20.087] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[17:40:20.087] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[17:40:20.088] INFO: ROC 4 masking pixel 21/60
[17:40:20.088] INFO: ROC 5 masking pixel 19/50
[17:40:20.088] INFO: ROC 5 masking pixel 47/58
[17:40:20.088] INFO: ROC 9 masking pixel 9/50
[17:40:20.088] INFO: ROC 14 masking pixel 45/29
[17:40:20.088] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:40:20.510] INFO: PixTestAlive::maskTest() done
[17:40:20.510] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:40:20.510] INFO: ROC 4 masking pixel 21/60
[17:40:20.510] INFO: ROC 5 masking pixel 19/50
[17:40:20.510] INFO: ROC 5 masking pixel 47/58
[17:40:20.510] INFO: ROC 9 masking pixel 9/50
[17:40:20.510] INFO: ROC 14 masking pixel 45/29
[17:40:20.528] INFO: ----------------------------------------------------------------------
[17:40:20.528] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:40:20.528] INFO: ----------------------------------------------------------------------
[17:40:20.530] INFO: ROC 4 masking pixel 21/60
[17:40:20.530] INFO: ROC 5 masking pixel 19/50
[17:40:20.530] INFO: ROC 5 masking pixel 47/58
[17:40:20.530] INFO: ROC 9 masking pixel 9/50
[17:40:20.530] INFO: ROC 14 masking pixel 45/29
[17:40:20.530] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:40:20.906] INFO: Expecting 41600 events.
[17:40:25.164] INFO: 41600 events read in total (3543ms).
[17:40:25.166] INFO: Test took 4636ms.
[17:40:25.175] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:40:25.175] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66555
[17:40:25.175] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[17:40:25.549] INFO: PixTestAlive::addressDecodingTest() done with 5 decoding errors
[17:40:25.549] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:40:25.549] INFO: ROC 4 masking pixel 21/60
[17:40:25.549] INFO: ROC 5 masking pixel 19/50
[17:40:25.549] INFO: ROC 5 masking pixel 47/58
[17:40:25.549] INFO: ROC 9 masking pixel 9/50
[17:40:25.549] INFO: ROC 14 masking pixel 45/29
[17:40:25.549] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[17:40:36.722] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[17:40:36.722] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[17:40:36.722] INFO: ----------------------------------------------------------------------
[17:40:36.722] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:40:36.722] INFO: ----------------------------------------------------------------------
[17:40:36.861] INFO: Expecting 768 events.
[17:40:37.994] INFO: 768 events read in total (417ms).
[17:40:37.994] INFO: Test took 1266ms.
[17:40:37.997] INFO: ROC 4 masking pixel 21/60
[17:40:37.997] INFO: ROC 5 masking pixel 19/50
[17:40:37.997] INFO: ROC 5 masking pixel 47/58
[17:40:37.997] INFO: ROC 9 masking pixel 9/50
[17:40:37.997] INFO: ROC 14 masking pixel 45/29
[17:40:38.000] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:40:38.797] INFO: Expecting 41600 events.
[17:40:41.965] INFO: 41600 events read in total (2641ms).
[17:40:41.969] INFO: Test took 3969ms.
[17:40:42.054] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:40:42.054] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 498750
[17:40:42.054] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[17:40:42.054] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:40:42.099] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:40:42.766] INFO: Expecting 41600 events.
[17:40:46.098] INFO: 41600 events read in total (2805ms).
[17:40:46.102] INFO: Test took 4003ms.
[17:40:46.188] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:40:46.188] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 510522
[17:40:46.188] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[17:40:46.188] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:40:46.232] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:40:46.897] INFO: Expecting 41600 events.
[17:40:50.268] INFO: 41600 events read in total (2845ms).
[17:40:50.272] INFO: Test took 4040ms.
[17:40:50.356] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:40:50.356] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 512683
[17:40:50.356] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[17:40:50.356] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:40:50.402] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:40:51.067] INFO: Expecting 41600 events.
[17:40:54.431] INFO: 41600 events read in total (2838ms).
[17:40:54.435] INFO: Test took 4033ms.
[17:40:54.518] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:40:54.518] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 511707
[17:40:54.518] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[17:40:54.519] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:40:54.564] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:40:55.229] INFO: Expecting 41600 events.
[17:40:58.605] INFO: 41600 events read in total (2849ms).
[17:40:58.609] INFO: Test took 4045ms.
[17:40:58.693] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:40:58.693] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 511748
[17:40:58.694] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[17:40:58.694] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:40:58.739] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:40:59.404] INFO: Expecting 41600 events.
[17:41:02.781] INFO: 41600 events read in total (2851ms).
[17:41:02.785] INFO: Test took 4046ms.
[17:41:02.869] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:02.869] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 512052
[17:41:02.869] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[17:41:02.869] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:41:02.914] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:41:03.579] INFO: Expecting 41600 events.
[17:41:06.962] INFO: 41600 events read in total (2856ms).
[17:41:06.966] INFO: Test took 4052ms.
[17:41:07.050] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:07.050] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 512546
[17:41:07.050] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[17:41:07.050] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:41:07.096] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:41:07.761] INFO: Expecting 41600 events.
[17:41:11.149] INFO: 41600 events read in total (2862ms).
[17:41:11.153] INFO: Test took 4057ms.
[17:41:11.237] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:11.237] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 512820
[17:41:11.237] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[17:41:11.237] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:41:11.283] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:41:11.948] INFO: Expecting 41600 events.
[17:41:15.347] INFO: 41600 events read in total (2872ms).
[17:41:15.351] INFO: Test took 4068ms.
[17:41:15.436] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:15.436] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 512800
[17:41:15.436] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[17:41:15.437] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:41:15.482] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:41:16.147] INFO: Expecting 41600 events.
[17:41:19.544] INFO: 41600 events read in total (2871ms).
[17:41:19.548] INFO: Test took 4066ms.
[17:41:19.635] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:19.635] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 513523
[17:41:19.635] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[17:41:19.635] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:41:19.680] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:41:20.345] INFO: Expecting 41600 events.
[17:41:23.751] INFO: 41600 events read in total (2879ms).
[17:41:23.755] INFO: Test took 4075ms.
[17:41:23.838] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:23.838] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 513841
[17:41:23.838] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[17:41:23.839] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:41:23.884] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:41:24.549] INFO: Expecting 41600 events.
[17:41:27.929] INFO: 41600 events read in total (2853ms).
[17:41:27.933] INFO: Test took 4049ms.
[17:41:28.019] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:28.019] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 512881
[17:41:28.019] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[17:41:28.020] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:41:28.066] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:41:28.733] INFO: Expecting 41600 events.
[17:41:32.132] INFO: 41600 events read in total (2873ms).
[17:41:32.136] INFO: Test took 4070ms.
[17:41:32.219] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:32.219] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 512281
[17:41:32.219] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[17:41:32.220] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:41:32.265] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:41:32.930] INFO: Expecting 41600 events.
[17:41:36.313] INFO: 41600 events read in total (2856ms).
[17:41:36.317] INFO: Test took 4052ms.
[17:41:36.401] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:36.402] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 513061
[17:41:36.402] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[17:41:36.402] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:41:36.447] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:41:37.112] INFO: Expecting 41600 events.
[17:41:40.510] INFO: 41600 events read in total (2871ms).
[17:41:40.514] INFO: Test took 4067ms.
[17:41:40.600] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:40.600] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 513468
[17:41:40.600] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[17:41:40.600] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:41:40.647] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:41:41.314] INFO: Expecting 41600 events.
[17:41:44.699] INFO: 41600 events read in total (2858ms).
[17:41:44.703] INFO: Test took 4056ms.
[17:41:44.788] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:44.788] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 512718
[17:41:44.788] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[17:41:44.788] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:41:44.835] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:41:45.502] INFO: Expecting 41600 events.
[17:41:48.893] INFO: 41600 events read in total (2864ms).
[17:41:48.897] INFO: Test took 4062ms.
[17:41:48.982] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:48.982] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 512505
[17:41:48.982] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[17:41:48.983] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:41:49.030] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:41:49.696] INFO: Expecting 41600 events.
[17:41:53.077] INFO: 41600 events read in total (2854ms).
[17:41:53.081] INFO: Test took 4051ms.
[17:41:53.167] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:53.167] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 511525
[17:41:53.167] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[17:41:53.167] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:41:53.214] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:41:53.880] INFO: Expecting 41600 events.
[17:41:57.267] INFO: 41600 events read in total (2860ms).
[17:41:57.270] INFO: Test took 4056ms.
[17:41:57.354] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:41:57.354] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 512935
[17:41:57.354] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[17:41:57.355] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:41:57.400] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:41:58.065] INFO: Expecting 41600 events.
[17:42:01.343] INFO: 41600 events read in total (2751ms).
[17:42:01.347] INFO: Test took 3947ms.
[17:42:01.432] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:42:01.432] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 510031
[17:42:01.432] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[17:42:01.432] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:42:01.699] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 159 eff = 0.999688
[17:42:01.699] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 148 eff = 0.999712
[17:42:01.699] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 147 eff = 0.999303
[17:42:01.699] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 164 eff = 0.999014
[17:42:01.699] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 211 eff = 0.998534
[17:42:01.700] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 174 eff = 0.998389
[17:42:01.700] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 175 eff = 0.99899
[17:42:01.700] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 190 eff = 0.999087
[17:42:01.700] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 160 eff = 0.99887
[17:42:01.701] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 158 eff = 0.998365
[17:42:01.701] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 166 eff = 0.998822
[17:42:01.701] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 160 eff = 0.998894
[17:42:01.701] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 175 eff = 0.999063
[17:42:01.702] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 185 eff = 0.965144
[17:42:01.702] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 178 eff = 0.999471
[17:42:01.702] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 166 eff = 0.999784
[17:42:35.890] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[17:42:35.890] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[17:42:35.890] INFO: ----------------------------------------------------------------------
[17:42:35.890] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:42:35.890] INFO: ----------------------------------------------------------------------
[17:42:35.891] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[17:42:35.891] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[17:42:35.891] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[17:42:35.891] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[17:42:35.891] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[17:42:35.891] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[17:42:35.891] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[17:42:35.891] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[17:42:35.891] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[17:42:35.891] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[17:42:35.891] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[17:42:35.891] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[17:42:35.906] INFO: ROC 4 masking pixel 21/60
[17:42:35.906] INFO: ROC 5 masking pixel 19/50
[17:42:35.906] INFO: ROC 5 masking pixel 47/58
[17:42:35.906] INFO: ROC 9 masking pixel 9/50
[17:42:35.906] INFO: ROC 14 masking pixel 45/29
[17:42:35.907] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:42:36.572] INFO: Expecting 208000 events.
[17:42:49.435] INFO: 208000 events read in total (12336ms).
[17:42:49.448] INFO: Test took 13541ms.
[17:42:49.854] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:42:49.854] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2276912
[17:42:49.854] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[17:42:49.855] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:42:50.247] INFO: number of dead pixels (per ROC): 0 0 0 0 1 2 0 0 0 1 0 0 0 0 1 0
[17:42:50.247] INFO: number of red-efficiency pixels: 114 88 156 259 251 279 278 271 278 307 257 229 210 312 93 114
[17:42:50.247] INFO: number of X-ray hits detected: 82515 65409 105897 158393 173554 171624 173817 162896 177886 179936 172901 162156 160463 113507 66529 82874
[17:42:50.247] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:42:50.247] INFO: number of Vcal hits detected: 207883 207911 207839 207732 207685 207613 207692 207710 207717 207629 207731 207758 207785 201426 207858 207885
[17:42:50.247] 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 98.3 100.0 99.9
[17:42:50.247] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.8 99.9 99.9 99.9 99.8 99.9 99.9 99.9 96.8 99.9 99.9
[17:42:50.247] INFO: X-ray hit rate [MHz/cm2]: 24.2 19.2 31.0 46.4 50.9 50.3 50.9 47.7 52.1 52.7 50.7 47.5 47.0 33.3 19.5 24.3
[17:42:50.247] INFO: PixTestHighRate::doXPixelAlive() done
[17:42:50.293] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[17:42:50.293] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[17:42:50.293] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[17:42:50.293] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[17:42:50.293] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[17:42:50.293] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[17:42:50.293] INFO: PixTest:: pg_setup set to default.
[17:43:28.218] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:43:28.219] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[17:43:28.220] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 472.7mA
[17:43:28.220] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[17:43:28.220] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:43:28.220] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:43:28.220] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:43:28.220] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:43:28.220] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:43:28.220] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:43:28.220] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:43:28.220] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:43:28.220] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:43:28.221] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:43:28.300] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:43:28.300] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:43:28.309] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:43:28.309] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:43:28.309] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:43:28.309] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:43:28.309] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:43:28.309] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:43:28.312] QUIET: Connection to board 58 closed.
[17:43:28.392] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[17:37:11.548] INFO: *** Welcome to pxar ***
[17:37:11.548] INFO: *** Today: 2016/06/01
[17:37:11.570] INFO: *** Version: v1.9.0-805-geb36
[17:37:11.571] INFO: readRocDacs: data/mi302/dacParameters35_C0.dat .. data/mi302/dacParameters35_C15.dat
[17:37:11.572] INFO: readTbmDacs: data/mi302/tbmParameters_C0a.dat .. data/mi302/tbmParameters_C0b.dat
[17:37:11.572] INFO: readMaskFile: data/mi302/defaultMaskFile.dat
[17:37:11.572] INFO: MASKED Roc 4 col/row: 21 60
[17:37:11.572] INFO: MASKED Roc 5 col/row: 19 50
[17:37:11.572] INFO: MASKED Roc 5 col/row: 47 58
[17:37:11.572] INFO: MASKED Roc 9 col/row: 9 50
[17:37:11.572] INFO: MASKED Roc 14 col/row: 45 29
[17:37:11.572] INFO: readTrimFile: data/mi302/trimParameters35_C0.dat .. data/mi302/trimParameters35_C15.dat
[17:37:11.610] INFO: masking Roc 4 col/row: 21 60
[17:37:11.619] INFO: masking Roc 5 col/row: 19 50
[17:37:11.620] INFO: masking Roc 5 col/row: 47 58
[17:37:11.657] INFO: masking Roc 9 col/row: 9 50
[17:37:11.705] INFO: masking Roc 14 col/row: 45 29
[17:37:11.725] INFO: clk: 4
[17:37:11.725] INFO: ctr: 4
[17:37:11.725] INFO: sda: 19
[17:37:11.725] INFO: tin: 9
[17:37:11.725] INFO: level: 15
[17:37:11.725] INFO: triggerdelay: 0
[17:37:11.725] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:37:11.725] INFO: Log level: DEBUG
[17:37:11.737] QUIET: Connection to board DTB_WRPRHI opened.
[17:37:11.740] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[17:37:11.743] INFO: RPC call hashes of host and DTB match: 398089610
[17:37:13.345] INFO: DUT info:
[17:37:13.346] INFO: The DUT currently contains the following objects:
[17:37:13.346] INFO: 2 TBM Cores tbm08c (2 ON)
[17:37:13.346] INFO: TBM Core alpha (0): 7 registers set
[17:37:13.346] INFO: TBM Core beta (1): 7 registers set
[17:37:13.346] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:37:13.346] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:37:13.346] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:37:13.346] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:37:13.346] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:37:13.346] INFO: ROC 4: 19 DACs set, Pixels: 1 masked, 0 active.
[17:37:13.346] INFO: ROC 5: 19 DACs set, Pixels: 2 masked, 0 active.
[17:37:13.346] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:37:13.346] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:37:13.346] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:37:13.346] INFO: ROC 9: 19 DACs set, Pixels: 1 masked, 0 active.
[17:37:13.346] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:37:13.346] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:37:13.346] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:37:13.346] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:37:13.346] INFO: ROC 14: 19 DACs set, Pixels: 1 masked, 0 active.
[17:37:13.346] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:37:13.347] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:37:13.348] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:37:13.349] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:37:13.350] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:37:13.354] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33300480
[17:37:13.354] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x234dbd0
[17:37:13.354] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x20efe60
[17:37:13.354] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f10e5d94010
[17:37:13.354] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f10ebfff510
[17:37:13.354] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33308672 fPxarMemory = 0x7f10e5d94010
[17:37:13.355] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 386.7mA
[17:37:13.356] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 472.7mA
[17:37:13.357] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[17:37:13.853] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2738860
[17:37:13.914] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:37:13.915] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:37:13.915] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:37:13.923] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:37:13.923] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:37:13.923] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:37:13.935] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:37:13.935] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:37:13.952] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:37:13.952] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:37:13.963] INFO: PixTestCmd::init()
[17:37:13.969] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:37:13.969] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:37:13.969] INFO: readGainPedestalParameters data/mi302/phCalibrationFitErr35_C0.dat .. data/mi302/phCalibrationFitErr35_C15.dat
[17:37:14.216] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:37:14.216] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:37:14.230] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:37:14.236] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:37:14.236] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:37:14.236] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:37:14.259] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:37:14.259] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:37:14.259] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:37:14.259] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:37:14.259] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:37:14.266] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:37:14.266] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:37:14.282] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:37:14.282] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:37:14.282] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:37:14.282] INFO: readReadbackCal: data/mi302/readbackCal_C0.dat .. data/mi302/readbackCal_C15.dat
[17:37:14.292] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:37:14.300] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:37:14.300] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:37:14.300] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:37:14.300] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:37:14.319] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:37:14.319] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:37:16.621] INFO: ######################################################################
[17:37:16.621] INFO: PixTestAlive::doTest()
[17:37:16.621] INFO: ######################################################################
[17:37:16.625] INFO: ----------------------------------------------------------------------
[17:37:16.625] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:37:16.625] INFO: ----------------------------------------------------------------------
[17:37:16.627] INFO: ROC 4 masking pixel 21/60
[17:37:16.627] INFO: ROC 5 masking pixel 19/50
[17:37:16.627] INFO: ROC 5 masking pixel 47/58
[17:37:16.627] INFO: ROC 9 masking pixel 9/50
[17:37:16.627] INFO: ROC 14 masking pixel 45/29
[17:37:16.627] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:37:17.003] INFO: Expecting 41600 events.
[17:37:21.258] INFO: 41600 events read in total (3537ms).
[17:37:21.405] INFO: Test took 4778ms.
[17:37:21.414] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:37:21.414] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66555
[17:37:21.414] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[17:37:21.690] INFO: PixTestAlive::aliveTest() done with 7 decoding errors
[17:37:21.690] INFO: number of dead pixels (per ROC): 0 0 0 0 1 2 0 0 0 1 0 0 0 0 1 0
[17:37:21.690] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 36 19 54 56 65 72 62 50 68 72 58 57 65 49 19 44
[17:37:21.690] INFO: ROC 4 masking pixel 21/60
[17:37:21.690] INFO: ROC 5 masking pixel 19/50
[17:37:21.690] INFO: ROC 5 masking pixel 47/58
[17:37:21.690] INFO: ROC 9 masking pixel 9/50
[17:37:21.690] INFO: ROC 14 masking pixel 45/29
[17:37:21.711] INFO: ----------------------------------------------------------------------
[17:37:21.711] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:37:21.711] INFO: ----------------------------------------------------------------------
[17:37:21.713] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:37:22.089] INFO: Expecting 41600 events.
[17:37:25.145] INFO: 41600 events read in total (2341ms).
[17:37:25.145] INFO: Test took 3432ms.
[17:37:25.145] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:37:25.145] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[17:37:25.145] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[17:37:25.146] INFO: ROC 4 masking pixel 21/60
[17:37:25.146] INFO: ROC 5 masking pixel 19/50
[17:37:25.146] INFO: ROC 5 masking pixel 47/58
[17:37:25.146] INFO: ROC 9 masking pixel 9/50
[17:37:25.146] INFO: ROC 14 masking pixel 45/29
[17:37:25.146] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:37:25.570] INFO: PixTestAlive::maskTest() done
[17:37:25.570] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:37:25.570] INFO: ROC 4 masking pixel 21/60
[17:37:25.570] INFO: ROC 5 masking pixel 19/50
[17:37:25.570] INFO: ROC 5 masking pixel 47/58
[17:37:25.570] INFO: ROC 9 masking pixel 9/50
[17:37:25.570] INFO: ROC 14 masking pixel 45/29
[17:37:25.589] INFO: ----------------------------------------------------------------------
[17:37:25.589] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:37:25.589] INFO: ----------------------------------------------------------------------
[17:37:25.591] INFO: ROC 4 masking pixel 21/60
[17:37:25.591] INFO: ROC 5 masking pixel 19/50
[17:37:25.591] INFO: ROC 5 masking pixel 47/58
[17:37:25.591] INFO: ROC 9 masking pixel 9/50
[17:37:25.591] INFO: ROC 14 masking pixel 45/29
[17:37:25.591] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:37:25.965] INFO: Expecting 41600 events.
[17:37:30.216] INFO: 41600 events read in total (3536ms).
[17:37:30.218] INFO: Test took 4627ms.
[17:37:30.229] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:37:30.229] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66555
[17:37:30.229] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[17:37:30.600] INFO: PixTestAlive::addressDecodingTest() done with 7 decoding errors
[17:37:30.600] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:37:30.600] INFO: ROC 4 masking pixel 21/60
[17:37:30.600] INFO: ROC 5 masking pixel 19/50
[17:37:30.600] INFO: ROC 5 masking pixel 47/58
[17:37:30.600] INFO: ROC 9 masking pixel 9/50
[17:37:30.600] INFO: ROC 14 masking pixel 45/29
[17:37:30.600] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[17:37:32.266] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[17:37:32.266] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[17:37:32.266] INFO: ----------------------------------------------------------------------
[17:37:32.266] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:37:32.266] INFO: ----------------------------------------------------------------------
[17:37:32.405] INFO: Expecting 768 events.
[17:37:33.538] INFO: 768 events read in total (417ms).
[17:37:33.538] INFO: Test took 1266ms.
[17:37:33.541] INFO: ROC 4 masking pixel 21/60
[17:37:33.541] INFO: ROC 5 masking pixel 19/50
[17:37:33.541] INFO: ROC 5 masking pixel 47/58
[17:37:33.541] INFO: ROC 9 masking pixel 9/50
[17:37:33.541] INFO: ROC 14 masking pixel 45/29
[17:37:33.544] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:37:34.342] INFO: Expecting 41600 events.
[17:37:37.845] INFO: 41600 events read in total (2977ms).
[17:37:37.850] INFO: Test took 4306ms.
[17:37:37.985] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:37:37.985] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 770776
[17:37:37.985] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[17:37:37.985] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:37:38.043] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:37:38.709] INFO: Expecting 41600 events.
[17:37:42.344] INFO: 41600 events read in total (3108ms).
[17:37:42.349] INFO: Test took 4306ms.
[17:37:42.477] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:37:42.477] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 782270
[17:37:42.477] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[17:37:42.477] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:37:42.537] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:37:43.202] INFO: Expecting 41600 events.
[17:37:46.878] INFO: 41600 events read in total (3149ms).
[17:37:46.883] INFO: Test took 4346ms.
[17:37:47.016] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:37:47.016] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783574
[17:37:47.016] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[17:37:47.016] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:37:47.075] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:37:47.740] INFO: Expecting 41600 events.
[17:37:51.410] INFO: 41600 events read in total (3143ms).
[17:37:51.415] INFO: Test took 4340ms.
[17:37:51.543] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:37:51.543] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 784044
[17:37:51.543] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[17:37:51.544] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:37:51.604] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:37:52.269] INFO: Expecting 41600 events.
[17:37:55.952] INFO: 41600 events read in total (3156ms).
[17:37:55.957] INFO: Test took 4353ms.
[17:37:56.088] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:37:56.088] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783286
[17:37:56.088] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[17:37:56.089] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:37:56.150] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:37:56.817] INFO: Expecting 41600 events.
[17:38:00.496] INFO: 41600 events read in total (3153ms).
[17:38:00.501] INFO: Test took 4351ms.
[17:38:00.633] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:00.633] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 782110
[17:38:00.633] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[17:38:00.633] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:38:00.694] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:38:01.360] INFO: Expecting 41600 events.
[17:38:05.053] INFO: 41600 events read in total (3166ms).
[17:38:05.058] INFO: Test took 4364ms.
[17:38:05.187] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:05.187] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783793
[17:38:05.187] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[17:38:05.187] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:38:05.248] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:38:05.913] INFO: Expecting 41600 events.
[17:38:09.607] INFO: 41600 events read in total (3167ms).
[17:38:09.612] INFO: Test took 4364ms.
[17:38:09.742] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:09.742] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783353
[17:38:09.742] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[17:38:09.742] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:38:09.802] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:38:10.467] INFO: Expecting 41600 events.
[17:38:14.162] INFO: 41600 events read in total (3168ms).
[17:38:14.167] INFO: Test took 4365ms.
[17:38:14.295] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:14.295] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783070
[17:38:14.295] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[17:38:14.296] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:38:14.355] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:38:15.020] INFO: Expecting 41600 events.
[17:38:18.710] INFO: 41600 events read in total (3164ms).
[17:38:18.714] INFO: Test took 4359ms.
[17:38:18.841] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:18.841] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783585
[17:38:18.842] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[17:38:18.842] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:38:18.902] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:38:19.567] INFO: Expecting 41600 events.
[17:38:23.258] INFO: 41600 events read in total (3164ms).
[17:38:23.263] INFO: Test took 4361ms.
[17:38:23.394] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:23.394] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783330
[17:38:23.394] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[17:38:23.394] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:38:23.454] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:38:24.119] INFO: Expecting 41600 events.
[17:38:27.829] INFO: 41600 events read in total (3184ms).
[17:38:27.834] INFO: Test took 4380ms.
[17:38:27.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:27.963] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783982
[17:38:27.963] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[17:38:27.963] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:38:28.023] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:38:28.688] INFO: Expecting 41600 events.
[17:38:32.400] INFO: 41600 events read in total (3186ms).
[17:38:32.405] INFO: Test took 4382ms.
[17:38:32.537] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:32.537] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 784486
[17:38:32.537] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[17:38:32.537] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:38:32.597] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:38:33.262] INFO: Expecting 41600 events.
[17:38:36.964] INFO: 41600 events read in total (3176ms).
[17:38:36.969] INFO: Test took 4372ms.
[17:38:37.100] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:37.100] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 783702
[17:38:37.100] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[17:38:37.100] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:38:37.159] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:38:37.824] INFO: Expecting 41600 events.
[17:38:41.543] INFO: 41600 events read in total (3192ms).
[17:38:41.548] INFO: Test took 4389ms.
[17:38:41.677] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:41.677] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 782988
[17:38:41.677] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[17:38:41.677] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:38:41.737] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:38:42.402] INFO: Expecting 41600 events.
[17:38:46.104] INFO: 41600 events read in total (3175ms).
[17:38:46.109] INFO: Test took 4372ms.
[17:38:46.237] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:46.237] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 782745
[17:38:46.237] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[17:38:46.238] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:38:46.297] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:38:46.962] INFO: Expecting 41600 events.
[17:38:50.663] INFO: 41600 events read in total (3174ms).
[17:38:50.668] INFO: Test took 4371ms.
[17:38:50.799] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:50.799] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 782210
[17:38:50.799] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[17:38:50.799] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:38:50.859] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:38:51.524] INFO: Expecting 41600 events.
[17:38:55.224] INFO: 41600 events read in total (3173ms).
[17:38:55.229] INFO: Test took 4370ms.
[17:38:55.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:55.360] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 781905
[17:38:55.360] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[17:38:55.360] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:38:55.420] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:38:56.085] INFO: Expecting 41600 events.
[17:38:59.769] INFO: 41600 events read in total (3158ms).
[17:38:59.774] INFO: Test took 4354ms.
[17:38:59.902] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:38:59.902] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 782189
[17:38:59.902] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[17:38:59.902] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:38:59.963] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:39:00.628] INFO: Expecting 41600 events.
[17:39:04.234] INFO: 41600 events read in total (3079ms).
[17:39:04.239] INFO: Test took 4276ms.
[17:39:04.366] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:39:04.366] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 780459
[17:39:04.366] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[17:39:04.367] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:39:04.648] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 175 eff = 0.999135
[17:39:04.649] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 153 eff = 0.999375
[17:39:04.649] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 156 eff = 0.99863
[17:39:04.649] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 172 eff = 0.997813
[17:39:04.649] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 211 eff = 0.997212
[17:39:04.649] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 174 eff = 0.996611
[17:39:04.649] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 173 eff = 0.996707
[17:39:04.649] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 191 eff = 0.997091
[17:39:04.650] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 159 eff = 0.996947
[17:39:04.650] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 161 eff = 0.996707
[17:39:04.650] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 163 eff = 0.997043
[17:39:04.650] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 163 eff = 0.997139
[17:39:04.650] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 177 eff = 0.997452
[17:39:04.650] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 185 eff = 0.964375
[17:39:04.650] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 177 eff = 0.999183
[17:39:04.651] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 160 eff = 0.999111
[17:39:07.738] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[17:39:07.738] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[17:39:07.738] INFO: ----------------------------------------------------------------------
[17:39:07.738] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:39:07.738] INFO: ----------------------------------------------------------------------
[17:39:07.738] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[17:39:07.738] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[17:39:07.738] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[17:39:07.739] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[17:39:07.739] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[17:39:07.739] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[17:39:07.739] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[17:39:07.739] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[17:39:07.739] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[17:39:07.739] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[17:39:07.739] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[17:39:07.739] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[17:39:07.753] INFO: ROC 4 masking pixel 21/60
[17:39:07.753] INFO: ROC 5 masking pixel 19/50
[17:39:07.753] INFO: ROC 5 masking pixel 47/58
[17:39:07.753] INFO: ROC 9 masking pixel 9/50
[17:39:07.753] INFO: ROC 14 masking pixel 45/29
[17:39:07.754] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:39:08.420] INFO: Expecting 208000 events.
[17:39:23.544] INFO: 208000 events read in total (14597ms).
[17:39:23.563] INFO: Test took 15809ms.
[17:39:24.183] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:39:24.183] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3611290
[17:39:24.183] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[17:39:24.183] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:39:24.649] INFO: number of dead pixels (per ROC): 0 0 0 0 1 2 0 0 0 1 0 0 0 0 1 0
[17:39:24.650] INFO: number of red-efficiency pixels: 191 130 336 486 552 582 580 521 584 622 535 526 472 413 135 229
[17:39:24.650] INFO: number of X-ray hits detected: 132486 104579 170459 254869 277787 275700 279553 261772 284808 288640 276077 261097 257134 179640 107312 132822
[17:39:24.650] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:39:24.650] INFO: number of Vcal hits detected: 207799 207862 207630 207482 207347 207262 207365 207434 207378 207253 207415 207422 207467 200648 207812 207765
[17:39:24.650] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.7 99.7 99.7 99.7 99.7 99.7 99.8 98.1 99.9 99.9
[17:39:24.650] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.6 99.7 99.7 99.7 99.6 99.7 99.7 99.7 96.5 99.9 99.9
[17:39:24.650] INFO: X-ray hit rate [MHz/cm2]: 38.8 30.7 50.0 74.7 81.4 80.8 81.9 76.7 83.5 84.6 80.9 76.5 75.4 52.7 31.5 38.9
[17:39:24.650] INFO: PixTestHighRate::doXPixelAlive() done
[17:39:24.696] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[17:39:24.696] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[17:39:24.696] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[17:39:24.696] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[17:39:24.696] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[17:39:24.696] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[17:39:24.696] INFO: PixTest:: pg_setup set to default.
[17:39:55.850] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:39:55.851] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[17:39:55.852] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 472.7mA
[17:39:55.852] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[17:39:55.852] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:39:55.852] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:39:55.852] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:39:55.852] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:39:55.852] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:39:55.852] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:39:55.852] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:39:55.852] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:39:55.852] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:39:55.852] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:39:55.930] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:39:55.930] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:39:55.939] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:39:55.939] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:39:55.939] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:39:55.939] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:39:55.939] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:39:55.939] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:39:55.942] QUIET: Connection to board 58 closed.
[17:39:56.021] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[17:33:41.926] INFO: *** Welcome to pxar ***
[17:33:41.926] INFO: *** Today: 2016/06/01
[17:33:41.948] INFO: *** Version: v1.9.0-805-geb36
[17:33:41.948] INFO: readRocDacs: data/mi302/dacParameters35_C0.dat .. data/mi302/dacParameters35_C15.dat
[17:33:41.949] INFO: readTbmDacs: data/mi302/tbmParameters_C0a.dat .. data/mi302/tbmParameters_C0b.dat
[17:33:41.949] INFO: readMaskFile: data/mi302/defaultMaskFile.dat
[17:33:41.949] INFO: MASKED Roc 4 col/row: 21 60
[17:33:41.949] INFO: MASKED Roc 5 col/row: 19 50
[17:33:41.949] INFO: MASKED Roc 5 col/row: 47 58
[17:33:41.950] INFO: MASKED Roc 9 col/row: 9 50
[17:33:41.950] INFO: MASKED Roc 14 col/row: 45 29
[17:33:41.950] INFO: readTrimFile: data/mi302/trimParameters35_C0.dat .. data/mi302/trimParameters35_C15.dat
[17:33:41.990] INFO: masking Roc 4 col/row: 21 60
[17:33:42.000] INFO: masking Roc 5 col/row: 19 50
[17:33:42.000] INFO: masking Roc 5 col/row: 47 58
[17:33:42.040] INFO: masking Roc 9 col/row: 9 50
[17:33:42.089] INFO: masking Roc 14 col/row: 45 29
[17:33:42.109] INFO: clk: 4
[17:33:42.109] INFO: ctr: 4
[17:33:42.109] INFO: sda: 19
[17:33:42.109] INFO: tin: 9
[17:33:42.109] INFO: level: 15
[17:33:42.109] INFO: triggerdelay: 0
[17:33:42.109] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[17:33:42.109] INFO: Log level: DEBUG
[17:33:42.120] QUIET: Connection to board DTB_WRPRHI opened.
[17:33:42.124] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[17:33:42.126] INFO: RPC call hashes of host and DTB match: 398089610
[17:33:43.726] INFO: DUT info:
[17:33:43.726] INFO: The DUT currently contains the following objects:
[17:33:43.726] INFO: 2 TBM Cores tbm08c (2 ON)
[17:33:43.726] INFO: TBM Core alpha (0): 7 registers set
[17:33:43.726] INFO: TBM Core beta (1): 7 registers set
[17:33:43.726] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:33:43.726] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:33:43.726] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:33:43.726] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:33:43.726] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:33:43.726] INFO: ROC 4: 19 DACs set, Pixels: 1 masked, 0 active.
[17:33:43.726] INFO: ROC 5: 19 DACs set, Pixels: 2 masked, 0 active.
[17:33:43.726] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:33:43.726] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:33:43.726] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:33:43.726] INFO: ROC 9: 19 DACs set, Pixels: 1 masked, 0 active.
[17:33:43.726] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:33:43.726] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:33:43.726] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:33:43.726] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:33:43.726] INFO: ROC 14: 19 DACs set, Pixels: 1 masked, 0 active.
[17:33:43.726] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:33:43.726] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:33:43.726] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:33:43.726] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:33:43.726] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:33:43.726] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:33:43.726] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:33:43.726] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:33:43.726] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:33:43.726] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:33:43.726] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:33:43.727] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:33:43.728] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:33:43.729] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:33:43.729] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:33:43.729] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:33:43.729] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:33:43.729] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:33:43.729] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:33:43.729] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:33:43.729] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:33:43.729] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:33:43.729] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:33:43.729] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:33:43.729] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:33:43.729] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:33:43.731] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35377152
[17:33:43.731] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x29c8bd0
[17:33:43.731] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x276ae60
[17:33:43.731] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f7759d94010
[17:33:43.731] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f775ffff510
[17:33:43.731] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35385344 fPxarMemory = 0x7f7759d94010
[17:33:43.732] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 386.7mA
[17:33:43.733] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 471.9mA
[17:33:43.733] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[17:33:44.223] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2db3860
[17:33:44.285] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:33:44.286] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:33:44.286] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:33:44.291] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:33:44.291] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:33:44.292] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:33:44.302] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:33:44.302] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:33:44.320] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:33:44.320] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:33:44.339] INFO: PixTestCmd::init()
[17:33:44.352] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:33:44.352] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:33:44.352] INFO: readGainPedestalParameters data/mi302/phCalibrationFitErr35_C0.dat .. data/mi302/phCalibrationFitErr35_C15.dat
[17:33:44.596] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:33:44.596] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:33:44.624] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:33:44.637] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:33:44.638] DEBUG: <PixTestHighRate.cc/init:L198> PixTestHighRate::init()
[17:33:44.638] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:33:44.659] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:33:44.659] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:33:44.659] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:33:44.659] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:33:44.659] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:33:44.668] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:33:44.668] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:33:44.682] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:33:44.682] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:33:44.682] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:33:44.683] INFO: readReadbackCal: data/mi302/readbackCal_C0.dat .. data/mi302/readbackCal_C15.dat
[17:33:44.698] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:33:44.706] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[17:33:44.706] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[17:33:44.706] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[17:33:44.706] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[17:33:44.727] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:33:44.727] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:33:50.749] INFO: ######################################################################
[17:33:50.749] INFO: PixTestAlive::doTest()
[17:33:50.749] INFO: ######################################################################
[17:33:50.753] INFO: ----------------------------------------------------------------------
[17:33:50.753] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:33:50.753] INFO: ----------------------------------------------------------------------
[17:33:50.755] INFO: ROC 4 masking pixel 21/60
[17:33:50.755] INFO: ROC 5 masking pixel 19/50
[17:33:50.755] INFO: ROC 5 masking pixel 47/58
[17:33:50.755] INFO: ROC 9 masking pixel 9/50
[17:33:50.755] INFO: ROC 14 masking pixel 45/29
[17:33:50.755] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:33:51.130] INFO: Expecting 41600 events.
[17:33:55.387] INFO: 41600 events read in total (3538ms).
[17:33:55.534] INFO: Test took 4779ms.
[17:33:55.543] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:55.543] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66555
[17:33:55.543] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[17:33:55.819] INFO: PixTestAlive::aliveTest() done with 16 decoding errors
[17:33:55.819] INFO: number of dead pixels (per ROC): 0 0 0 0 1 2 0 0 0 1 0 0 0 0 1 0
[17:33:55.819] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 54 40 60 75 75 90 76 113 85 98 81 88 79 66 35 40
[17:33:55.819] INFO: ROC 4 masking pixel 21/60
[17:33:55.819] INFO: ROC 5 masking pixel 19/50
[17:33:55.819] INFO: ROC 5 masking pixel 47/58
[17:33:55.819] INFO: ROC 9 masking pixel 9/50
[17:33:55.819] INFO: ROC 14 masking pixel 45/29
[17:33:55.840] INFO: ----------------------------------------------------------------------
[17:33:55.841] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:33:55.841] INFO: ----------------------------------------------------------------------
[17:33:55.843] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:33:56.217] INFO: Expecting 41600 events.
[17:33:59.263] INFO: 41600 events read in total (2331ms).
[17:33:59.263] INFO: Test took 3420ms.
[17:33:59.263] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:59.263] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[17:33:59.263] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[17:33:59.263] INFO: ROC 4 masking pixel 21/60
[17:33:59.263] INFO: ROC 5 masking pixel 19/50
[17:33:59.264] INFO: ROC 5 masking pixel 47/58
[17:33:59.264] INFO: ROC 9 masking pixel 9/50
[17:33:59.264] INFO: ROC 14 masking pixel 45/29
[17:33:59.264] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:33:59.686] INFO: PixTestAlive::maskTest() done
[17:33:59.686] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:33:59.686] INFO: ROC 4 masking pixel 21/60
[17:33:59.686] INFO: ROC 5 masking pixel 19/50
[17:33:59.686] INFO: ROC 5 masking pixel 47/58
[17:33:59.686] INFO: ROC 9 masking pixel 9/50
[17:33:59.686] INFO: ROC 14 masking pixel 45/29
[17:33:59.705] INFO: ----------------------------------------------------------------------
[17:33:59.705] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:33:59.705] INFO: ----------------------------------------------------------------------
[17:33:59.707] INFO: ROC 4 masking pixel 21/60
[17:33:59.707] INFO: ROC 5 masking pixel 19/50
[17:33:59.707] INFO: ROC 5 masking pixel 47/58
[17:33:59.707] INFO: ROC 9 masking pixel 9/50
[17:33:59.707] INFO: ROC 14 masking pixel 45/29
[17:33:59.707] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:34:00.082] INFO: Expecting 41600 events.
[17:34:04.344] INFO: 41600 events read in total (3546ms).
[17:34:04.345] INFO: Test took 4638ms.
[17:34:04.355] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:04.355] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66555
[17:34:04.355] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[17:34:04.726] INFO: PixTestAlive::addressDecodingTest() done with 10 decoding errors
[17:34:04.726] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:34:04.726] INFO: ROC 4 masking pixel 21/60
[17:34:04.726] INFO: ROC 5 masking pixel 19/50
[17:34:04.726] INFO: ROC 5 masking pixel 47/58
[17:34:04.726] INFO: ROC 9 masking pixel 9/50
[17:34:04.726] INFO: ROC 14 masking pixel 45/29
[17:34:04.726] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[17:34:05.778] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[17:34:05.778] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: caldelscan
[17:34:05.778] INFO: ----------------------------------------------------------------------
[17:34:05.778] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:34:05.778] INFO: ----------------------------------------------------------------------
[17:34:05.917] INFO: Expecting 768 events.
[17:34:07.050] INFO: 768 events read in total (417ms).
[17:34:07.050] INFO: Test took 1266ms.
[17:34:07.053] INFO: ROC 4 masking pixel 21/60
[17:34:07.053] INFO: ROC 5 masking pixel 19/50
[17:34:07.053] INFO: ROC 5 masking pixel 47/58
[17:34:07.053] INFO: ROC 9 masking pixel 9/50
[17:34:07.053] INFO: ROC 14 masking pixel 45/29
[17:34:07.056] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:34:07.854] INFO: Expecting 41600 events.
[17:34:11.616] INFO: 41600 events read in total (3236ms).
[17:34:11.622] INFO: Test took 4566ms.
[17:34:11.791] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:11.792] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 984562
[17:34:11.792] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[17:34:11.792] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:34:11.863] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:34:12.530] INFO: Expecting 41600 events.
[17:34:16.424] INFO: 41600 events read in total (3368ms).
[17:34:16.430] INFO: Test took 4567ms.
[17:34:16.593] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:16.594] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 996321
[17:34:16.594] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[17:34:16.594] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:34:16.667] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:34:17.334] INFO: Expecting 41600 events.
[17:34:21.242] INFO: 41600 events read in total (3381ms).
[17:34:21.248] INFO: Test took 4581ms.
[17:34:21.413] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:21.413] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 997682
[17:34:21.413] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[17:34:21.413] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:34:21.484] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:34:22.149] INFO: Expecting 41600 events.
[17:34:26.060] INFO: 41600 events read in total (3385ms).
[17:34:26.067] INFO: Test took 4583ms.
[17:34:26.229] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:26.229] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 997806
[17:34:26.229] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[17:34:26.230] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:34:26.305] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:34:26.971] INFO: Expecting 41600 events.
[17:34:30.897] INFO: 41600 events read in total (3399ms).
[17:34:30.905] INFO: Test took 4600ms.
[17:34:31.071] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:31.071] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 997497
[17:34:31.071] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[17:34:31.071] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:34:31.146] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:34:31.812] INFO: Expecting 41600 events.
[17:34:35.747] INFO: 41600 events read in total (3408ms).
[17:34:35.755] INFO: Test took 4609ms.
[17:34:35.926] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:35.926] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 998692
[17:34:35.926] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[17:34:35.926] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:34:35.998] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:34:36.663] INFO: Expecting 41600 events.
[17:34:40.603] INFO: 41600 events read in total (3413ms).
[17:34:40.611] INFO: Test took 4613ms.
[17:34:40.779] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:40.779] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 999291
[17:34:40.779] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[17:34:40.780] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:34:40.853] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:34:41.518] INFO: Expecting 41600 events.
[17:34:45.479] INFO: 41600 events read in total (3435ms).
[17:34:45.487] INFO: Test took 4634ms.
[17:34:45.652] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:45.652] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 997725
[17:34:45.652] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[17:34:45.652] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:34:45.725] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:34:46.390] INFO: Expecting 41600 events.
[17:34:50.350] INFO: 41600 events read in total (3433ms).
[17:34:50.357] INFO: Test took 4632ms.
[17:34:50.522] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:50.522] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1000447
[17:34:50.522] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[17:34:50.522] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:34:50.595] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:34:51.260] INFO: Expecting 41600 events.
[17:34:55.216] INFO: 41600 events read in total (3429ms).
[17:34:55.224] INFO: Test took 4629ms.
[17:34:55.388] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:55.388] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 999470
[17:34:55.388] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[17:34:55.389] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:34:55.463] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:34:56.128] INFO: Expecting 41600 events.
[17:35:00.090] INFO: 41600 events read in total (3435ms).
[17:35:00.098] INFO: Test took 4635ms.
[17:35:00.264] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:35:00.264] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1000325
[17:35:00.265] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[17:35:00.265] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:35:00.338] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:35:01.004] INFO: Expecting 41600 events.
[17:35:04.962] INFO: 41600 events read in total (3432ms).
[17:35:04.970] INFO: Test took 4632ms.
[17:35:05.135] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:35:05.136] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1000381
[17:35:05.136] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[17:35:05.136] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:35:05.209] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:35:05.875] INFO: Expecting 41600 events.
[17:35:09.835] INFO: 41600 events read in total (3434ms).
[17:35:09.842] INFO: Test took 4633ms.
[17:35:10.010] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:35:10.010] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1000154
[17:35:10.010] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[17:35:10.010] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:35:10.085] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:35:10.750] INFO: Expecting 41600 events.
[17:35:14.729] INFO: 41600 events read in total (3452ms).
[17:35:14.736] INFO: Test took 4651ms.
[17:35:14.901] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:35:14.901] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 997059
[17:35:14.901] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[17:35:14.901] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:35:14.976] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:35:15.640] INFO: Expecting 41600 events.
[17:35:19.598] INFO: 41600 events read in total (3432ms).
[17:35:19.606] INFO: Test took 4630ms.
[17:35:19.771] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:35:19.771] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 998358
[17:35:19.771] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[17:35:19.772] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:35:19.845] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:35:20.510] INFO: Expecting 41600 events.
[17:35:24.466] INFO: 41600 events read in total (3430ms).
[17:35:24.474] INFO: Test took 4629ms.
[17:35:24.636] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:35:24.636] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 999190
[17:35:24.636] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[17:35:24.637] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:35:24.711] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:35:25.376] INFO: Expecting 41600 events.
[17:35:29.339] INFO: 41600 events read in total (3437ms).
[17:35:29.346] INFO: Test took 4635ms.
[17:35:29.513] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:35:29.513] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 998441
[17:35:29.513] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[17:35:29.513] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:35:29.586] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:35:30.251] INFO: Expecting 41600 events.
[17:35:34.211] INFO: 41600 events read in total (3433ms).
[17:35:34.219] INFO: Test took 4633ms.
[17:35:34.381] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:35:34.381] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 999152
[17:35:34.381] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[17:35:34.382] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:35:34.455] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:35:35.120] INFO: Expecting 41600 events.
[17:35:39.068] INFO: 41600 events read in total (3422ms).
[17:35:39.076] INFO: Test took 4621ms.
[17:35:39.239] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:35:39.239] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 998373
[17:35:39.240] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[17:35:39.240] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:35:39.314] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:35:39.979] INFO: Expecting 41600 events.
[17:35:43.814] INFO: 41600 events read in total (3309ms).
[17:35:43.819] INFO: Test took 4505ms.
[17:35:43.984] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:35:43.984] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 994912
[17:35:43.984] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[17:35:43.984] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:35:44.277] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 0: caldel = 178 eff = 0.998918
[17:35:44.278] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 1: caldel = 145 eff = 0.998726
[17:35:44.278] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 2: caldel = 161 eff = 0.997428
[17:35:44.278] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 3: caldel = 171 eff = 0.995216
[17:35:44.278] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 4: caldel = 211 eff = 0.994255
[17:35:44.279] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 5: caldel = 175 eff = 0.994111
[17:35:44.279] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 6: caldel = 172 eff = 0.993846
[17:35:44.279] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 7: caldel = 195 eff = 0.994688
[17:35:44.279] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 8: caldel = 158 eff = 0.994063
[17:35:44.279] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 9: caldel = 163 eff = 0.99387
[17:35:44.280] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 10: caldel = 166 eff = 0.99399
[17:35:44.280] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 11: caldel = 160 eff = 0.994423
[17:35:44.280] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 12: caldel = 173 eff = 0.99488
[17:35:44.280] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 13: caldel = 180 eff = 0.964303
[17:35:44.280] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 14: caldel = 176 eff = 0.998486
[17:35:44.280] DEBUG: <PixTestHighRate.cc/doCalDelScan:L422> roc 15: caldel = 169 eff = 0.998606
[17:35:49.474] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[17:35:49.474] DEBUG: <PixTestHighRate.cc/runCommand:L157> running command: xpixelalive
[17:35:49.474] INFO: ----------------------------------------------------------------------
[17:35:49.474] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:35:49.474] INFO: ----------------------------------------------------------------------
[17:35:49.474] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: clk: 4
[17:35:49.474] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: ctr: 4
[17:35:49.474] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: sda: 19
[17:35:49.474] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: tin: 9
[17:35:49.474] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: level: 15
[17:35:49.474] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L451> old set: triggerdelay: 0
[17:35:49.474] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: clk: 4
[17:35:49.474] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: ctr: 4
[17:35:49.474] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: sda: 19
[17:35:49.475] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: tin: 9
[17:35:49.475] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: level: 15
[17:35:49.475] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L467> setting: triggerdelay: 20
[17:35:49.490] INFO: ROC 4 masking pixel 21/60
[17:35:49.490] INFO: ROC 5 masking pixel 19/50
[17:35:49.490] INFO: ROC 5 masking pixel 47/58
[17:35:49.490] INFO: ROC 9 masking pixel 9/50
[17:35:49.490] INFO: ROC 14 masking pixel 45/29
[17:35:49.490] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:35:50.157] INFO: Expecting 208000 events.
[17:36:07.046] INFO: 208000 events read in total (16362ms).
[17:36:07.070] INFO: Test took 17580ms.
[17:36:07.893] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:36:07.893] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4669329
[17:36:07.893] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[17:36:07.893] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:36:08.421] INFO: number of dead pixels (per ROC): 0 0 0 0 1 2 0 0 0 1 0 0 0 63 1 0
[17:36:08.421] INFO: number of red-efficiency pixels: 262 242 492 800 931 961 951 942 997 1075 973 901 866 555 208 314
[17:36:08.421] INFO: number of X-ray hits detected: 172481 135736 221212 330121 360330 357590 364520 340925 368738 376037 358674 338576 333870 232331 139361 172335
[17:36:08.421] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:36:08.421] INFO: number of Vcal hits detected: 207724 207743 207402 207070 206853 206759 206833 206801 206817 206650 206824 206916 206969 199736 207731 207669
[17:36:08.421] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.7 99.6 99.5 99.5 99.5 99.5 99.5 99.4 99.5 99.5 99.5 98.6 99.9 99.8
[17:36:08.421] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.6 99.4 99.4 99.4 99.4 99.4 99.4 99.4 99.5 99.5 96.0 99.9 99.8
[17:36:08.421] INFO: X-ray hit rate [MHz/cm2]: 50.6 39.8 64.8 96.8 105.6 104.8 106.8 99.9 108.1 110.2 105.1 99.2 97.9 68.1 40.8 50.5
[17:36:08.421] INFO: PixTestHighRate::doXPixelAlive() done
[17:36:08.467] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: clk: 4
[17:36:08.467] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: ctr: 4
[17:36:08.467] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: sda: 19
[17:36:08.467] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: tin: 9
[17:36:08.467] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: level: 15
[17:36:08.467] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L586> resetting: triggerdelay: 0
[17:36:08.467] INFO: PixTest:: pg_setup set to default.
[17:37:02.034] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:37:02.035] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[17:37:02.036] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 472.7mA
[17:37:02.036] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[17:37:02.036] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:37:02.036] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:37:02.036] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:37:02.036] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:37:02.036] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:37:02.036] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:37:02.036] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:37:02.036] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:37:02.036] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:37:02.036] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L245> PixTestHighRate dtor
[17:37:02.117] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:37:02.117] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:37:02.126] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:37:02.127] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:37:02.127] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:37:02.127] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[17:37:02.127] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:37:02.127] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:37:02.129] QUIET: Connection to board 58 closed.
[17:37:02.209] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master