Test Date: 1973-11-29 16:33
Analysis date: 2016-09-21 19:48
Logfile
hrData_40.log
[15:29:43.875] INFO: *** Welcome to pxar ***
[15:29:43.875] INFO: *** Today: 2016/09/21
[15:29:43.899] INFO: *** Version: v1.9.0-818-g96727
[15:29:43.899] INFO: readRocDacs: data/mqa25/dacParameters35_C0.dat .. data/mqa25/dacParameters35_C15.dat
[15:29:43.900] INFO: readTbmDacs: data/mqa25/tbmParameters_C0a.dat .. data/mqa25/tbmParameters_C0b.dat
[15:29:43.900] INFO: readMaskFile: data/mqa25/defaultMaskFile.dat
[15:29:43.900] INFO: readTrimFile: data/mqa25/trimParameters35_C0.dat .. data/mqa25/trimParameters35_C15.dat
[15:29:44.051] INFO: clk: 4
[15:29:44.051] INFO: ctr: 4
[15:29:44.051] INFO: sda: 19
[15:29:44.051] INFO: tin: 9
[15:29:44.051] INFO: level: 15
[15:29:44.051] INFO: triggerdelay: 0
[15:29:44.051] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:29:44.051] INFO: Log level: DEBUG
[15:29:44.062] QUIET: Connection to board DTB_WRPRHI opened.
[15:29:44.065] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:29:44.068] INFO: RPC call hashes of host and DTB match: 398089610
[15:29:45.669] INFO: DUT info:
[15:29:45.669] INFO: The DUT currently contains the following objects:
[15:29:45.669] INFO: 2 TBM Cores tbm08c (2 ON)
[15:29:45.669] INFO: TBM Core alpha (0): 7 registers set
[15:29:45.669] INFO: TBM Core beta (1): 7 registers set
[15:29:45.669] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:29:45.669] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.669] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.669] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.669] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.669] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.669] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.669] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.670] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.670] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.670] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.670] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.670] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.670] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.670] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.670] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.670] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:29:45.670] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:29:45.671] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:29:45.672] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:29:45.673] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:29:45.674] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:29:45.674] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:29:45.674] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:29:45.674] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:29:45.674] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:29:45.674] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:29:45.674] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:29:45.674] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:29:45.674] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:29:45.674] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:29:45.676] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30334976
[15:29:45.676] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x11b50e0
[15:29:45.676] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xf57370
[15:29:45.676] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fbdd5d94010
[15:29:45.676] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fbddbfff510
[15:29:45.676] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30343168 fPxarMemory = 0x7fbdd5d94010
[15:29:45.677] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[15:29:45.678] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[15:29:45.678] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[15:29:46.170] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x15a3f70
[15:29:46.236] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:29:46.236] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:29:46.236] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:29:46.243] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:29:46.243] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:29:46.243] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:29:46.256] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:29:46.256] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:29:46.280] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:29:46.281] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:29:46.296] INFO: PixTestCmd::init()
[15:29:46.311] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:29:46.311] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:29:46.311] INFO: readGainPedestalParameters data/mqa25/phCalibrationFitErr35_C0.dat .. data/mqa25/phCalibrationFitErr35_C15.dat
[15:29:46.561] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:29:46.561] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:29:46.575] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:29:46.582] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:29:46.582] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:29:46.582] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:29:46.605] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:29:46.605] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:29:46.605] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:29:46.605] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:29:46.605] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:29:46.611] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:29:46.612] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:29:46.626] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:29:46.626] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:29:46.626] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:29:46.626] INFO: readReadbackCal: data/mqa25/readbackCal_C0.dat .. data/mqa25/readbackCal_C15.dat
[15:29:46.641] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:29:46.651] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:29:46.651] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:29:46.651] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:29:46.651] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:29:46.674] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:29:46.675] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:29:49.398] INFO: ######################################################################
[15:29:49.398] INFO: PixTestXray::doTest()
[15:29:49.398] INFO: ######################################################################
[15:29:49.398] INFO: ----------------------------------------------------------------------
[15:29:49.398] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:29:49.398] INFO: ----------------------------------------------------------------------
[15:29:50.372] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:30:02.418] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[15:30:02.422] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:30:29.633] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1186842 events.
[15:30:36.198] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1186842, pixels seen in all events: 6010153
[15:30:36.381] INFO: Resuming triggers.
[15:30:48.423] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[15:30:48.535] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:31:15.702] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1186297 events.
[15:31:22.348] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1186297, pixels seen in all events: 6017200
[15:31:22.536] INFO: Resuming triggers.
[15:31:34.578] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[15:31:34.621] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:32:01.714] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1186275 events.
[15:32:08.300] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1186275, pixels seen in all events: 6019853
[15:32:08.560] INFO: Resuming triggers.
[15:32:20.601] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[15:32:20.647] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:32:47.773] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1186237 events.
[15:32:54.368] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1186237, pixels seen in all events: 6020596
[15:32:54.629] INFO: Resuming triggers.
[15:33:06.665] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[15:33:06.708] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:33:33.832] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1185707 events.
[15:33:40.415] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1185707, pixels seen in all events: 6022468
[15:33:40.673] INFO: Resuming triggers.
[15:33:52.705] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[15:33:52.749] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:34:19.759] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1185338 events.
[15:34:26.438] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1185338, pixels seen in all events: 6027009
[15:34:26.621] INFO: Resuming triggers.
[15:34:38.657] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[15:34:38.702] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:35:05.769] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1185732 events.
[15:35:12.359] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1185732, pixels seen in all events: 6022126
[15:35:12.621] INFO: Resuming triggers.
[15:35:24.654] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[15:35:24.700] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:35:51.913] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1185472 events.
[15:35:58.587] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1185472, pixels seen in all events: 6025715
[15:35:58.775] INFO: Resuming triggers.
[15:36:02.596] INFO: data taking finished, elapsed time: 100 seconds.
[15:36:02.792] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:36:11.512] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 376394 events.
[15:36:13.608] DEBUG: <PixTestXray.cc/processData:L823> # events read: 376394, pixels seen in all events: 1914224
[15:36:13.695] INFO: PixTest:: pg_setup set to default.
[15:36:13.743] INFO: PixTestXray::doPhRun() done
[15:36:13.743] INFO: PixTestXray::doTest() done
[15:36:17.662] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:36:17.663] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 387.5mA
[15:36:17.663] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.1mA
[15:36:17.663] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:36:17.664] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:36:17.664] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:36:17.664] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:36:17.664] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:36:17.664] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:36:17.664] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:36:17.664] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:36:17.664] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:36:17.664] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:36:17.664] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:36:17.664] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:36:17.664] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:36:17.664] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:36:17.665] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:36:17.665] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:36:17.665] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:36:17.665] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:36:17.665] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:36:17.955] QUIET: Connection to board 58 closed.
[15:36:18.035] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[15:36:23.962] INFO: *** Welcome to pxar ***
[15:36:23.962] INFO: *** Today: 2016/09/21
[15:36:23.985] INFO: *** Version: v1.9.0-818-g96727
[15:36:23.985] INFO: readRocDacs: data/mqa25/dacParameters35_C0.dat .. data/mqa25/dacParameters35_C15.dat
[15:36:23.986] INFO: readTbmDacs: data/mqa25/tbmParameters_C0a.dat .. data/mqa25/tbmParameters_C0b.dat
[15:36:23.987] INFO: readMaskFile: data/mqa25/defaultMaskFile.dat
[15:36:23.987] INFO: readTrimFile: data/mqa25/trimParameters35_C0.dat .. data/mqa25/trimParameters35_C15.dat
[15:36:24.138] INFO: clk: 4
[15:36:24.138] INFO: ctr: 4
[15:36:24.138] INFO: sda: 19
[15:36:24.138] INFO: tin: 9
[15:36:24.138] INFO: level: 15
[15:36:24.138] INFO: triggerdelay: 0
[15:36:24.138] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:36:24.138] INFO: Log level: DEBUG
[15:36:24.149] QUIET: Connection to board DTB_WRPRHI opened.
[15:36:24.152] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:36:24.155] INFO: RPC call hashes of host and DTB match: 398089610
[15:36:25.758] INFO: DUT info:
[15:36:25.758] INFO: The DUT currently contains the following objects:
[15:36:25.758] INFO: 2 TBM Cores tbm08c (2 ON)
[15:36:25.758] INFO: TBM Core alpha (0): 7 registers set
[15:36:25.758] INFO: TBM Core beta (1): 7 registers set
[15:36:25.758] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:36:25.758] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.758] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.758] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.758] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.758] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.758] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.758] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.758] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.758] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.758] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.758] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.758] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.758] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.758] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.758] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.758] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:36:25.759] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:36:25.760] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:36:25.761] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:36:25.762] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:36:25.764] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30330880
[15:36:25.764] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x25280e0
[15:36:25.764] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x22ca370
[15:36:25.764] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f53c9d94010
[15:36:25.764] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f53cffff510
[15:36:25.764] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30339072 fPxarMemory = 0x7f53c9d94010
[15:36:25.766] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[15:36:25.767] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[15:36:25.767] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[15:36:26.261] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2916f80
[15:36:26.328] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:36:26.328] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:36:26.329] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:36:26.335] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:36:26.335] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:36:26.336] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:36:26.349] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:36:26.349] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:36:26.360] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:36:26.360] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:36:26.377] INFO: PixTestCmd::init()
[15:36:26.383] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:36:26.383] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:36:26.383] INFO: readGainPedestalParameters data/mqa25/phCalibrationFitErr35_C0.dat .. data/mqa25/phCalibrationFitErr35_C15.dat
[15:36:26.629] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:36:26.629] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:36:26.644] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:36:26.652] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:36:26.652] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:36:26.652] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:36:26.679] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:36:26.679] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:36:26.679] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:36:26.679] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:36:26.679] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:36:26.685] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:36:26.685] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:36:26.707] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:36:26.707] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:36:26.707] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:36:26.707] INFO: readReadbackCal: data/mqa25/readbackCal_C0.dat .. data/mqa25/readbackCal_C15.dat
[15:36:26.717] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:36:26.731] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:36:26.731] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:36:26.731] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:36:26.731] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:36:26.744] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:36:26.744] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:36:34.198] INFO: ######################################################################
[15:36:34.198] INFO: PixTestXray::doTest()
[15:36:34.198] INFO: ######################################################################
[15:36:34.198] INFO: ----------------------------------------------------------------------
[15:36:34.198] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:36:34.198] INFO: ----------------------------------------------------------------------
[15:36:35.172] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:36:42.893] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:36:42.897] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:37:08.133] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 760800 events.
[15:37:20.259] DEBUG: <PixTestXray.cc/processData:L823> # events read: 760800, pixels seen in all events: 11123834
[15:37:20.514] INFO: Resuming triggers.
[15:37:28.240] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[15:37:28.335] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:37:53.465] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761047 events.
[15:38:05.602] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761047, pixels seen in all events: 11119136
[15:38:05.770] INFO: Resuming triggers.
[15:38:13.501] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:38:13.550] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:38:38.764] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761638 events.
[15:38:50.895] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761638, pixels seen in all events: 11112615
[15:38:51.098] INFO: Resuming triggers.
[15:38:58.829] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[15:38:58.902] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:39:24.116] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761538 events.
[15:39:36.277] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761538, pixels seen in all events: 11113227
[15:39:36.515] INFO: Resuming triggers.
[15:39:44.248] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[15:39:44.295] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:40:09.472] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761798 events.
[15:40:21.575] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761798, pixels seen in all events: 11110881
[15:40:21.777] INFO: Resuming triggers.
[15:40:29.509] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:40:29.558] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:40:54.726] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761699 events.
[15:41:06.913] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761699, pixels seen in all events: 11111717
[15:41:07.149] INFO: Resuming triggers.
[15:41:14.882] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[15:41:14.925] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:41:40.095] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761797 events.
[15:41:52.271] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761797, pixels seen in all events: 11109867
[15:41:52.515] INFO: Resuming triggers.
[15:42:00.250] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[15:42:00.293] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:42:25.964] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 762055 events.
[15:42:38.082] DEBUG: <PixTestXray.cc/processData:L823> # events read: 762055, pixels seen in all events: 11107328
[15:42:38.274] INFO: Resuming triggers.
[15:42:46.007] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[15:42:46.091] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:43:11.288] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761760 events.
[15:43:23.425] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761760, pixels seen in all events: 11110938
[15:43:23.662] INFO: Resuming triggers.
[15:43:31.397] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[15:43:31.439] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:43:56.586] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761981 events.
[15:44:08.676] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761981, pixels seen in all events: 11108040
[15:44:08.911] INFO: Resuming triggers.
[15:44:16.646] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[15:44:16.689] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:44:42.025] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761919 events.
[15:44:54.115] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761919, pixels seen in all events: 11108271
[15:44:54.352] INFO: Resuming triggers.
[15:45:02.088] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[15:45:02.132] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:45:27.398] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 762067 events.
[15:45:39.521] DEBUG: <PixTestXray.cc/processData:L823> # events read: 762067, pixels seen in all events: 11107560
[15:45:39.755] INFO: Resuming triggers.
[15:45:47.109] INFO: data taking finished, elapsed time: 100 seconds.
[15:45:47.305] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:46:11.285] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 724408 events.
[15:46:22.852] DEBUG: <PixTestXray.cc/processData:L823> # events read: 724408, pixels seen in all events: 10558966
[15:46:23.081] INFO: PixTest:: pg_setup set to default.
[15:46:23.129] INFO: PixTestXray::doPhRun() done
[15:46:23.129] INFO: PixTestXray::doTest() done
[15:46:31.414] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:46:31.415] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 388.3mA
[15:46:31.416] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[15:46:31.416] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.7 C
[15:46:31.416] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:46:31.416] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:46:31.416] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:46:31.416] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:46:31.416] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:46:31.416] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:46:31.416] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:46:31.416] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:46:31.416] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:46:31.416] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:46:31.417] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:46:31.417] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:46:31.417] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:46:31.417] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:46:31.417] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:46:31.417] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:46:31.417] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:46:31.417] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:46:31.739] QUIET: Connection to board 58 closed.
[15:46:31.819] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[15:23:32.755] INFO: *** Welcome to pxar ***
[15:23:32.755] INFO: *** Today: 2016/09/21
[15:23:32.778] INFO: *** Version: v1.9.0-818-g96727
[15:23:32.779] INFO: readRocDacs: data/mqa25/dacParameters35_C0.dat .. data/mqa25/dacParameters35_C15.dat
[15:23:32.779] INFO: readTbmDacs: data/mqa25/tbmParameters_C0a.dat .. data/mqa25/tbmParameters_C0b.dat
[15:23:32.780] INFO: readMaskFile: data/mqa25/defaultMaskFile.dat
[15:23:32.780] INFO: readTrimFile: data/mqa25/trimParameters35_C0.dat .. data/mqa25/trimParameters35_C15.dat
[15:23:32.929] INFO: clk: 4
[15:23:32.929] INFO: ctr: 4
[15:23:32.929] INFO: sda: 19
[15:23:32.929] INFO: tin: 9
[15:23:32.929] INFO: level: 15
[15:23:32.929] INFO: triggerdelay: 0
[15:23:32.929] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:23:32.929] INFO: Log level: DEBUG
[15:23:32.940] QUIET: Connection to board DTB_WRPRHI opened.
[15:23:32.943] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:23:32.946] INFO: RPC call hashes of host and DTB match: 398089610
[15:23:34.547] INFO: DUT info:
[15:23:34.548] INFO: The DUT currently contains the following objects:
[15:23:34.548] INFO: 2 TBM Cores tbm08c (2 ON)
[15:23:34.548] INFO: TBM Core alpha (0): 7 registers set
[15:23:34.548] INFO: TBM Core beta (1): 7 registers set
[15:23:34.548] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:23:34.548] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.548] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.548] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.548] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.548] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.548] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.548] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.548] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.548] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.548] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.548] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.548] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.548] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.548] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.548] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.548] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:23:34.549] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:23:34.550] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:23:34.551] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:23:34.552] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:23:34.554] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30334976
[15:23:34.554] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x203c0e0
[15:23:34.554] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1dde370
[15:23:34.554] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f1841d94010
[15:23:34.554] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f1847fff510
[15:23:34.554] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30343168 fPxarMemory = 0x7f1841d94010
[15:23:34.556] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[15:23:34.557] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[15:23:34.557] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[15:23:35.053] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x242afc0
[15:23:35.116] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:23:35.116] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:23:35.116] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:23:35.122] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:23:35.122] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:23:35.122] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:23:35.131] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:23:35.131] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:23:35.140] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:23:35.140] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:23:35.160] INFO: PixTestCmd::init()
[15:23:35.184] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:23:35.185] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:23:35.185] INFO: readGainPedestalParameters data/mqa25/phCalibrationFitErr35_C0.dat .. data/mqa25/phCalibrationFitErr35_C15.dat
[15:23:35.435] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:23:35.436] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:23:35.454] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:23:35.462] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:23:35.462] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:23:35.462] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:23:35.491] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:23:35.492] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:23:35.492] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:23:35.492] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:23:35.492] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:23:35.498] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:23:35.498] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:23:35.519] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:23:35.519] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:23:35.519] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:23:35.519] INFO: readReadbackCal: data/mqa25/readbackCal_C0.dat .. data/mqa25/readbackCal_C15.dat
[15:23:35.538] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:23:35.546] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:23:35.546] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:23:35.546] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:23:35.546] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:23:35.560] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:23:35.560] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:23:42.769] INFO: ######################################################################
[15:23:42.769] INFO: PixTestAlive::doTest()
[15:23:42.769] INFO: ######################################################################
[15:23:42.772] INFO: ----------------------------------------------------------------------
[15:23:42.772] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:23:42.772] INFO: ----------------------------------------------------------------------
[15:23:42.775] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:23:43.154] INFO: Expecting 41600 events.
[15:23:47.551] INFO: 41600 events read in total (3679ms).
[15:23:47.699] INFO: Test took 4924ms.
[15:23:47.709] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:47.709] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[15:23:47.709] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:23:47.988] INFO: PixTestAlive::aliveTest() done with 5 decoding errors
[15:23:47.988] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:23:47.988] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 21 14 25 32 41 38 33 35 37 38 45 39 36 20 20 14
[15:23:48.016] INFO: ----------------------------------------------------------------------
[15:23:48.016] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:23:48.016] INFO: ----------------------------------------------------------------------
[15:23:48.019] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:23:48.396] INFO: Expecting 41600 events.
[15:23:51.511] INFO: 41600 events read in total (2399ms).
[15:23:51.511] INFO: Test took 3492ms.
[15:23:51.511] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:51.511] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:23:51.511] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:23:51.512] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:23:51.934] INFO: PixTestAlive::maskTest() done
[15:23:51.934] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:23:51.956] INFO: ----------------------------------------------------------------------
[15:23:51.956] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:23:51.956] INFO: ----------------------------------------------------------------------
[15:23:51.958] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:23:52.337] INFO: Expecting 41600 events.
[15:23:56.728] INFO: 41600 events read in total (3676ms).
[15:23:56.731] INFO: Test took 4773ms.
[15:23:56.741] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:23:56.741] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[15:23:56.741] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:23:57.111] INFO: PixTestAlive::addressDecodingTest() done with 5 decoding errors
[15:23:57.111] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:23:57.111] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:24:11.990] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:24:11.990] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:24:11.990] INFO: ----------------------------------------------------------------------
[15:24:11.990] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:24:11.990] INFO: ----------------------------------------------------------------------
[15:24:12.129] INFO: Expecting 768 events.
[15:24:13.263] INFO: 768 events read in total (419ms).
[15:24:13.263] INFO: Test took 1267ms.
[15:24:13.269] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:24:14.067] INFO: Expecting 41600 events.
[15:24:17.400] INFO: 41600 events read in total (2806ms).
[15:24:17.405] INFO: Test took 4136ms.
[15:24:17.485] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:17.485] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 456734
[15:24:17.485] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:24:17.485] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:24:17.522] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:24:18.187] INFO: Expecting 41600 events.
[15:24:21.620] INFO: 41600 events read in total (2906ms).
[15:24:21.624] INFO: Test took 4102ms.
[15:24:21.699] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:21.699] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 463562
[15:24:21.699] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:24:21.700] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:24:21.739] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:24:22.404] INFO: Expecting 41600 events.
[15:24:25.869] INFO: 41600 events read in total (2939ms).
[15:24:25.872] INFO: Test took 4133ms.
[15:24:25.949] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:25.949] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464837
[15:24:25.949] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:24:25.950] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:24:25.989] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:24:26.654] INFO: Expecting 41600 events.
[15:24:30.131] INFO: 41600 events read in total (2951ms).
[15:24:30.135] INFO: Test took 4146ms.
[15:24:30.212] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:30.212] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465289
[15:24:30.212] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:24:30.212] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:24:30.251] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:24:30.917] INFO: Expecting 41600 events.
[15:24:34.404] INFO: 41600 events read in total (2961ms).
[15:24:34.407] INFO: Test took 4156ms.
[15:24:34.485] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:34.485] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465219
[15:24:34.485] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:24:34.485] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:24:34.526] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:24:35.193] INFO: Expecting 41600 events.
[15:24:38.683] INFO: 41600 events read in total (2964ms).
[15:24:38.687] INFO: Test took 4161ms.
[15:24:38.764] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:38.764] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465289
[15:24:38.764] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:24:38.764] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:24:38.803] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:24:39.469] INFO: Expecting 41600 events.
[15:24:42.970] INFO: 41600 events read in total (2974ms).
[15:24:42.973] INFO: Test took 4170ms.
[15:24:43.051] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:43.051] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465549
[15:24:43.051] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:24:43.051] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:24:43.092] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:24:43.759] INFO: Expecting 41600 events.
[15:24:47.263] INFO: 41600 events read in total (2978ms).
[15:24:47.266] INFO: Test took 4174ms.
[15:24:47.345] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:47.345] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465273
[15:24:47.345] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:24:47.346] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:24:47.387] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:24:48.054] INFO: Expecting 41600 events.
[15:24:51.555] INFO: 41600 events read in total (2975ms).
[15:24:51.558] INFO: Test took 4171ms.
[15:24:51.637] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:51.637] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465430
[15:24:51.637] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:24:51.638] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:24:51.679] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:24:52.346] INFO: Expecting 41600 events.
[15:24:55.846] INFO: 41600 events read in total (2973ms).
[15:24:55.850] INFO: Test took 4171ms.
[15:24:55.928] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:24:55.928] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465296
[15:24:55.928] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:24:55.929] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:24:55.970] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:24:56.636] INFO: Expecting 41600 events.
[15:25:00.138] INFO: 41600 events read in total (2975ms).
[15:25:00.141] INFO: Test took 4171ms.
[15:25:00.219] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:00.219] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465492
[15:25:00.219] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:25:00.220] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:25:00.258] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:25:00.924] INFO: Expecting 41600 events.
[15:25:04.416] INFO: 41600 events read in total (2965ms).
[15:25:04.419] INFO: Test took 4161ms.
[15:25:04.497] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:04.497] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464991
[15:25:04.497] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:25:04.498] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:25:04.539] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:25:05.206] INFO: Expecting 41600 events.
[15:25:08.705] INFO: 41600 events read in total (2972ms).
[15:25:08.709] INFO: Test took 4170ms.
[15:25:08.787] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:08.787] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465993
[15:25:08.787] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:25:08.788] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:25:08.827] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:25:09.492] INFO: Expecting 41600 events.
[15:25:12.969] INFO: 41600 events read in total (2950ms).
[15:25:12.973] INFO: Test took 4146ms.
[15:25:13.048] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:13.049] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 466226
[15:25:13.049] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:25:13.049] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:25:13.089] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:25:13.754] INFO: Expecting 41600 events.
[15:25:17.255] INFO: 41600 events read in total (2975ms).
[15:25:17.259] INFO: Test took 4170ms.
[15:25:17.335] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:17.335] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465394
[15:25:17.335] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:25:17.335] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:25:17.375] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:25:18.039] INFO: Expecting 41600 events.
[15:25:21.538] INFO: 41600 events read in total (2972ms).
[15:25:21.541] INFO: Test took 4166ms.
[15:25:21.619] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:21.619] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464728
[15:25:21.620] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:25:21.620] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:25:21.661] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:25:22.329] INFO: Expecting 41600 events.
[15:25:25.823] INFO: 41600 events read in total (2968ms).
[15:25:25.826] INFO: Test took 4165ms.
[15:25:25.905] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:25.905] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465758
[15:25:25.905] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:25:25.906] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:25:25.945] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:25:26.610] INFO: Expecting 41600 events.
[15:25:30.095] INFO: 41600 events read in total (2958ms).
[15:25:30.098] INFO: Test took 4153ms.
[15:25:30.175] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:30.175] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464659
[15:25:30.175] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:25:30.175] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:25:30.215] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:25:30.881] INFO: Expecting 41600 events.
[15:25:34.347] INFO: 41600 events read in total (2939ms).
[15:25:34.351] INFO: Test took 4136ms.
[15:25:34.429] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:34.429] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 463381
[15:25:34.429] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:25:34.429] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:25:34.468] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:25:35.133] INFO: Expecting 41600 events.
[15:25:38.443] INFO: 41600 events read in total (2783ms).
[15:25:38.446] INFO: Test took 3978ms.
[15:25:38.523] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:38.523] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 457377
[15:25:38.523] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:25:38.523] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:25:38.783] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 124 eff = 0.998798
[15:25:38.783] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 148 eff = 0.99976
[15:25:38.784] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 125 eff = 0.999327
[15:25:38.784] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 157 eff = 0.999063
[15:25:38.784] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 148 eff = 0.998918
[15:25:38.784] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 146 eff = 0.998822
[15:25:38.785] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 141 eff = 0.999063
[15:25:38.785] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 168 eff = 0.999207
[15:25:38.785] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 175 eff = 0.998966
[15:25:38.785] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 164 eff = 0.998894
[15:25:38.786] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 151 eff = 0.998798
[15:25:38.786] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 157 eff = 0.999159
[15:25:38.786] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 158 eff = 0.998942
[15:25:38.786] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 156 eff = 0.999375
[15:25:38.787] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 167 eff = 0.999808
[15:25:38.787] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 152 eff = 0.999712
[15:26:22.718] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:26:22.718] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:26:22.718] INFO: ----------------------------------------------------------------------
[15:26:22.718] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:26:22.718] INFO: ----------------------------------------------------------------------
[15:26:22.718] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:26:22.718] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:26:22.718] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:26:22.718] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:26:22.718] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:26:22.718] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:26:22.718] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:26:22.719] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:26:22.719] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:26:22.719] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:26:22.719] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:26:22.719] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:26:22.739] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:26:23.409] INFO: Expecting 208000 events.
[15:26:36.766] INFO: 208000 events read in total (12830ms).
[15:26:36.778] INFO: Test took 14039ms.
[15:26:37.130] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:37.130] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2043876
[15:26:37.130] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:26:37.130] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:26:37.499] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:26:37.499] INFO: number of red-efficiency pixels: 255 67 171 190 213 254 229 214 232 254 258 217 208 149 71 110
[15:26:37.499] INFO: number of X-ray hits detected: 81092 55333 96281 144920 153828 152949 149284 140370 154192 164460 160496 145440 150741 99346 57575 71009
[15:26:37.499] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:26:37.499] INFO: number of Vcal hits detected: 207538 207931 207821 207802 207775 207735 207766 207778 207762 207741 207724 207770 207785 207846 207928 207886
[15:26:37.499] INFO: Vcal hit fiducial efficiency (%): 99.8 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[15:26:37.499] INFO: Vcal hit overall efficiency (%): 99.8 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[15:26:37.499] INFO: X-ray hit rate [MHz/cm2]: 23.8 16.2 28.2 42.5 45.1 44.8 43.8 41.1 45.2 48.2 47.0 42.6 44.2 29.1 16.9 20.8
[15:26:37.499] INFO: PixTestHighRate::doXPixelAlive() done
[15:26:37.544] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:26:37.544] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:26:37.544] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:26:37.544] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:26:37.544] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:26:37.544] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:26:37.544] INFO: PixTest:: pg_setup set to default.
[15:26:57.902] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:26:57.903] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 387.5mA
[15:26:57.903] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[15:26:57.903] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[15:26:57.903] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:26:57.903] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:26:57.903] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:26:57.904] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:26:57.904] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:26:57.904] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:26:57.904] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:26:57.904] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:26:57.904] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:26:57.904] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:26:57.986] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:26:57.986] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:26:57.996] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:26:57.996] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:26:57.996] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:26:57.996] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:26:57.996] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:26:57.996] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:26:57.998] QUIET: Connection to board 58 closed.
[15:26:58.078] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[15:20:00.732] INFO: *** Welcome to pxar ***
[15:20:00.732] INFO: *** Today: 2016/09/21
[15:20:00.757] INFO: *** Version: v1.9.0-818-g96727
[15:20:00.757] INFO: readRocDacs: data/mqa25/dacParameters35_C0.dat .. data/mqa25/dacParameters35_C15.dat
[15:20:00.758] INFO: readTbmDacs: data/mqa25/tbmParameters_C0a.dat .. data/mqa25/tbmParameters_C0b.dat
[15:20:00.758] INFO: readMaskFile: data/mqa25/defaultMaskFile.dat
[15:20:00.758] INFO: readTrimFile: data/mqa25/trimParameters35_C0.dat .. data/mqa25/trimParameters35_C15.dat
[15:20:00.908] INFO: clk: 4
[15:20:00.908] INFO: ctr: 4
[15:20:00.908] INFO: sda: 19
[15:20:00.908] INFO: tin: 9
[15:20:00.908] INFO: level: 15
[15:20:00.908] INFO: triggerdelay: 0
[15:20:00.908] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:20:00.908] INFO: Log level: DEBUG
[15:20:00.919] QUIET: Connection to board DTB_WRPRHI opened.
[15:20:00.923] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:20:00.926] INFO: RPC call hashes of host and DTB match: 398089610
[15:20:02.528] INFO: DUT info:
[15:20:02.528] INFO: The DUT currently contains the following objects:
[15:20:02.528] INFO: 2 TBM Cores tbm08c (2 ON)
[15:20:02.528] INFO: TBM Core alpha (0): 7 registers set
[15:20:02.528] INFO: TBM Core beta (1): 7 registers set
[15:20:02.528] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:20:02.528] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.528] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.528] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.528] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.528] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.528] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.528] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.528] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.528] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.528] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.528] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.528] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.528] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.528] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.528] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.529] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:20:02.529] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:20:02.530] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:20:02.531] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:20:02.532] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:20:02.535] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30339072
[15:20:02.535] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x15030e0
[15:20:02.535] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x12a5370
[15:20:02.535] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fe88dd94010
[15:20:02.535] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fe893fff510
[15:20:02.535] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30347264 fPxarMemory = 0x7fe88dd94010
[15:20:02.536] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[15:20:02.537] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[15:20:02.537] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[15:20:03.029] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x18f1fd0
[15:20:03.098] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:20:03.099] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:20:03.099] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:20:03.105] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:20:03.105] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:20:03.105] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:20:03.115] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:20:03.115] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:20:03.133] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:20:03.133] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:20:03.152] INFO: PixTestCmd::init()
[15:20:03.168] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:20:03.168] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:20:03.168] INFO: readGainPedestalParameters data/mqa25/phCalibrationFitErr35_C0.dat .. data/mqa25/phCalibrationFitErr35_C15.dat
[15:20:03.415] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:20:03.415] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:20:03.429] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:20:03.435] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:20:03.435] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:20:03.435] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:20:03.460] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:20:03.460] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:20:03.460] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:20:03.460] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:20:03.460] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:20:03.466] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:20:03.466] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:20:03.489] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:20:03.489] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:20:03.489] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:20:03.490] INFO: readReadbackCal: data/mqa25/readbackCal_C0.dat .. data/mqa25/readbackCal_C15.dat
[15:20:03.499] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:20:03.507] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:20:03.507] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:20:03.507] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:20:03.507] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:20:03.527] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:20:03.527] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:20:07.057] INFO: ######################################################################
[15:20:07.057] INFO: PixTestAlive::doTest()
[15:20:07.057] INFO: ######################################################################
[15:20:07.061] INFO: ----------------------------------------------------------------------
[15:20:07.061] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:20:07.061] INFO: ----------------------------------------------------------------------
[15:20:07.063] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:20:07.443] INFO: Expecting 41600 events.
[15:20:11.828] INFO: 41600 events read in total (3667ms).
[15:20:11.974] INFO: Test took 4911ms.
[15:20:11.985] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:11.986] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[15:20:11.986] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:20:12.267] INFO: PixTestAlive::aliveTest() done with 7 decoding errors
[15:20:12.267] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:20:12.267] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 35 24 43 54 71 56 53 68 65 76 66 58 54 45 34 26
[15:20:12.293] INFO: ----------------------------------------------------------------------
[15:20:12.293] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:20:12.293] INFO: ----------------------------------------------------------------------
[15:20:12.295] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:20:12.676] INFO: Expecting 41600 events.
[15:20:15.806] INFO: 41600 events read in total (2414ms).
[15:20:15.806] INFO: Test took 3511ms.
[15:20:15.806] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:15.806] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:20:15.806] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:20:15.807] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:20:16.229] INFO: PixTestAlive::maskTest() done
[15:20:16.229] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:20:16.249] INFO: ----------------------------------------------------------------------
[15:20:16.249] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:20:16.249] INFO: ----------------------------------------------------------------------
[15:20:16.251] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:20:16.630] INFO: Expecting 41600 events.
[15:20:21.016] INFO: 41600 events read in total (3671ms).
[15:20:21.018] INFO: Test took 4767ms.
[15:20:21.029] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:21.029] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[15:20:21.029] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:20:21.398] INFO: PixTestAlive::addressDecodingTest() done with 6 decoding errors
[15:20:21.399] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:20:21.399] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:20:32.310] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:20:32.310] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:20:32.310] INFO: ----------------------------------------------------------------------
[15:20:32.310] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:20:32.310] INFO: ----------------------------------------------------------------------
[15:20:32.449] INFO: Expecting 768 events.
[15:20:33.583] INFO: 768 events read in total (418ms).
[15:20:33.584] INFO: Test took 1268ms.
[15:20:33.589] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:20:34.387] INFO: Expecting 41600 events.
[15:20:38.035] INFO: 41600 events read in total (3122ms).
[15:20:38.040] INFO: Test took 4451ms.
[15:20:38.165] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:38.165] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 709495
[15:20:38.165] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:20:38.165] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:20:38.218] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:20:38.883] INFO: Expecting 41600 events.
[15:20:42.647] INFO: 41600 events read in total (3237ms).
[15:20:42.652] INFO: Test took 4434ms.
[15:20:42.771] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:42.771] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 720105
[15:20:42.771] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:20:42.771] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:20:42.829] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:20:43.496] INFO: Expecting 41600 events.
[15:20:47.290] INFO: 41600 events read in total (3267ms).
[15:20:47.295] INFO: Test took 4466ms.
[15:20:47.418] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:47.418] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719314
[15:20:47.418] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:20:47.418] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:20:47.475] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:20:48.142] INFO: Expecting 41600 events.
[15:20:51.941] INFO: 41600 events read in total (3272ms).
[15:20:51.945] INFO: Test took 4469ms.
[15:20:52.067] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:52.067] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 721115
[15:20:52.067] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:20:52.068] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:20:52.125] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:20:52.792] INFO: Expecting 41600 events.
[15:20:56.588] INFO: 41600 events read in total (3269ms).
[15:20:56.593] INFO: Test took 4467ms.
[15:20:56.713] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:20:56.714] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 720877
[15:20:56.714] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:20:56.714] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:20:56.769] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:20:57.434] INFO: Expecting 41600 events.
[15:21:01.239] INFO: 41600 events read in total (3278ms).
[15:21:01.244] INFO: Test took 4475ms.
[15:21:01.367] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:01.367] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719244
[15:21:01.367] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:21:01.368] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:21:01.423] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:21:02.088] INFO: Expecting 41600 events.
[15:21:05.910] INFO: 41600 events read in total (3295ms).
[15:21:05.915] INFO: Test took 4492ms.
[15:21:06.035] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:06.035] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719388
[15:21:06.035] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:21:06.036] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:21:06.091] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:21:06.756] INFO: Expecting 41600 events.
[15:21:10.580] INFO: 41600 events read in total (3297ms).
[15:21:10.584] INFO: Test took 4493ms.
[15:21:10.708] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:10.708] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718426
[15:21:10.708] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:21:10.708] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:21:10.764] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:21:11.429] INFO: Expecting 41600 events.
[15:21:15.242] INFO: 41600 events read in total (3286ms).
[15:21:15.247] INFO: Test took 4483ms.
[15:21:15.368] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:15.368] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 721342
[15:21:15.368] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:21:15.368] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:21:15.425] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:21:16.090] INFO: Expecting 41600 events.
[15:21:19.911] INFO: 41600 events read in total (3294ms).
[15:21:19.915] INFO: Test took 4490ms.
[15:21:20.037] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:20.037] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719974
[15:21:20.037] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:21:20.038] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:21:20.093] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:21:20.758] INFO: Expecting 41600 events.
[15:21:24.588] INFO: 41600 events read in total (3303ms).
[15:21:24.592] INFO: Test took 4500ms.
[15:21:24.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:24.715] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 721865
[15:21:24.715] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:21:24.715] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:21:24.771] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:21:25.436] INFO: Expecting 41600 events.
[15:21:29.246] INFO: 41600 events read in total (3283ms).
[15:21:29.251] INFO: Test took 4480ms.
[15:21:29.371] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:29.371] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 720507
[15:21:29.371] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:21:29.371] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:21:29.427] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:21:30.092] INFO: Expecting 41600 events.
[15:21:33.897] INFO: 41600 events read in total (3278ms).
[15:21:33.902] INFO: Test took 4475ms.
[15:21:34.023] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:34.023] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719818
[15:21:34.023] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:21:34.023] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:21:34.079] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:21:34.744] INFO: Expecting 41600 events.
[15:21:38.563] INFO: 41600 events read in total (3293ms).
[15:21:38.568] INFO: Test took 4489ms.
[15:21:38.689] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:38.689] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 720628
[15:21:38.689] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:21:38.689] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:21:38.745] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:21:39.410] INFO: Expecting 41600 events.
[15:21:43.222] INFO: 41600 events read in total (3286ms).
[15:21:43.227] INFO: Test took 4482ms.
[15:21:43.347] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:43.347] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 720262
[15:21:43.347] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:21:43.347] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:21:43.403] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:21:44.068] INFO: Expecting 41600 events.
[15:21:47.886] INFO: 41600 events read in total (3291ms).
[15:21:47.891] INFO: Test took 4488ms.
[15:21:48.010] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:48.010] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719072
[15:21:48.010] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:21:48.011] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:21:48.066] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:21:48.731] INFO: Expecting 41600 events.
[15:21:52.550] INFO: 41600 events read in total (3292ms).
[15:21:52.554] INFO: Test took 4488ms.
[15:21:52.730] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:52.731] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719177
[15:21:52.731] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:21:52.731] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:21:52.790] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:21:53.457] INFO: Expecting 41600 events.
[15:21:57.270] INFO: 41600 events read in total (3287ms).
[15:21:57.275] INFO: Test took 4485ms.
[15:21:57.396] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:21:57.396] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 720172
[15:21:57.396] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:21:57.397] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:21:57.453] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:21:58.118] INFO: Expecting 41600 events.
[15:22:01.908] INFO: 41600 events read in total (3263ms).
[15:22:01.912] INFO: Test took 4459ms.
[15:22:02.032] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:22:02.032] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719591
[15:22:02.032] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:22:02.032] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:22:02.091] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:22:02.758] INFO: Expecting 41600 events.
[15:22:06.391] INFO: 41600 events read in total (3107ms).
[15:22:06.395] INFO: Test took 4304ms.
[15:22:06.514] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:22:06.514] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 711212
[15:22:06.514] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:22:06.514] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:22:06.791] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 135 eff = 0.997572
[15:22:06.791] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 148 eff = 0.999375
[15:22:06.791] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 143 eff = 0.998486
[15:22:06.791] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 156 eff = 0.997957
[15:22:06.792] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 151 eff = 0.997236
[15:22:06.792] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 146 eff = 0.99726
[15:22:06.792] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 144 eff = 0.99774
[15:22:06.792] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 167 eff = 0.997524
[15:22:06.793] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 174 eff = 0.997716
[15:22:06.793] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 164 eff = 0.997043
[15:22:06.793] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 149 eff = 0.997524
[15:22:06.793] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 163 eff = 0.997885
[15:22:06.794] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 155 eff = 0.99726
[15:22:06.794] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 165 eff = 0.998774
[15:22:06.794] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 155 eff = 0.999327
[15:22:06.794] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 142 eff = 0.999327
[15:22:35.630] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:22:35.630] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:22:35.630] INFO: ----------------------------------------------------------------------
[15:22:35.630] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:22:35.630] INFO: ----------------------------------------------------------------------
[15:22:35.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:22:35.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:22:35.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:22:35.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:22:35.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:22:35.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:22:35.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:22:35.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:22:35.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:22:35.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:22:35.630] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:22:35.631] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:22:35.648] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:22:36.316] INFO: Expecting 208000 events.
[15:22:51.739] INFO: 208000 events read in total (14896ms).
[15:22:51.757] INFO: Test took 16109ms.
[15:22:52.344] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:22:52.344] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3301940
[15:22:52.344] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:22:52.344] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:22:52.797] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:22:52.797] INFO: number of red-efficiency pixels: 377 153 293 448 520 537 476 465 480 570 618 494 567 278 161 194
[15:22:52.797] INFO: number of X-ray hits detected: 132434 90766 157437 236120 252774 251070 243861 228949 251906 270676 263323 237227 246081 162407 95083 115266
[15:22:52.797] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:22:52.797] INFO: number of Vcal hits detected: 207165 207842 207687 207519 207428 207407 207481 207497 207493 207376 207308 207451 207366 207701 207834 207794
[15:22:52.797] INFO: Vcal hit fiducial efficiency (%): 99.6 99.9 99.9 99.8 99.7 99.7 99.8 99.8 99.8 99.7 99.7 99.8 99.7 99.9 99.9 99.9
[15:22:52.797] INFO: Vcal hit overall efficiency (%): 99.6 99.9 99.8 99.8 99.7 99.7 99.8 99.8 99.8 99.7 99.7 99.7 99.7 99.9 99.9 99.9
[15:22:52.797] INFO: X-ray hit rate [MHz/cm2]: 38.8 26.6 46.1 69.2 74.1 73.6 71.5 67.1 73.8 79.3 77.2 69.5 72.1 47.6 27.9 33.8
[15:22:52.797] INFO: PixTestHighRate::doXPixelAlive() done
[15:22:52.842] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:22:52.842] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:22:52.842] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:22:52.842] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:22:52.842] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:22:52.842] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:22:52.842] INFO: PixTest:: pg_setup set to default.
[15:23:27.614] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:23:27.615] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 387.5mA
[15:23:27.615] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.1mA
[15:23:27.615] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[15:23:27.615] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:23:27.616] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:23:27.616] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:23:27.616] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:23:27.616] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:23:27.616] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:23:27.616] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:23:27.616] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:23:27.616] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:23:27.616] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:23:27.697] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:23:27.697] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:23:27.706] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:23:27.706] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:23:27.706] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:23:27.706] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:23:27.706] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:23:27.707] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:23:27.709] QUIET: Connection to board 58 closed.
[15:23:27.789] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[15:16:43.403] INFO: *** Welcome to pxar ***
[15:16:43.403] INFO: *** Today: 2016/09/21
[15:16:43.428] INFO: *** Version: v1.9.0-818-g96727
[15:16:43.428] INFO: readRocDacs: data/mqa25/dacParameters35_C0.dat .. data/mqa25/dacParameters35_C15.dat
[15:16:43.429] INFO: readTbmDacs: data/mqa25/tbmParameters_C0a.dat .. data/mqa25/tbmParameters_C0b.dat
[15:16:43.429] INFO: readMaskFile: data/mqa25/defaultMaskFile.dat
[15:16:43.430] INFO: readTrimFile: data/mqa25/trimParameters35_C0.dat .. data/mqa25/trimParameters35_C15.dat
[15:16:43.581] INFO: clk: 4
[15:16:43.581] INFO: ctr: 4
[15:16:43.581] INFO: sda: 19
[15:16:43.581] INFO: tin: 9
[15:16:43.581] INFO: level: 15
[15:16:43.581] INFO: triggerdelay: 0
[15:16:43.581] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[15:16:43.581] INFO: Log level: DEBUG
[15:16:43.592] QUIET: Connection to board DTB_WRPRHI opened.
[15:16:43.595] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:16:43.598] INFO: RPC call hashes of host and DTB match: 398089610
[15:16:45.199] INFO: DUT info:
[15:16:45.199] INFO: The DUT currently contains the following objects:
[15:16:45.199] INFO: 2 TBM Cores tbm08c (2 ON)
[15:16:45.199] INFO: TBM Core alpha (0): 7 registers set
[15:16:45.199] INFO: TBM Core beta (1): 7 registers set
[15:16:45.199] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:16:45.199] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.199] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.199] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.199] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.199] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.200] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.200] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.200] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.200] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.200] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.200] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.200] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.200] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.200] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.200] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.200] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:16:45.200] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:16:45.200] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:16:45.200] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:16:45.200] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:16:45.200] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:16:45.200] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:16:45.200] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:16:45.200] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:16:45.200] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:16:45.200] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:16:45.200] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:16:45.200] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:16:45.200] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:16:45.200] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:16:45.200] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:16:45.201] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:16:45.202] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:16:45.203] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:16:45.205] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30330880
[15:16:45.205] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1a780e0
[15:16:45.205] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x181a370
[15:16:45.205] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f0e91d94010
[15:16:45.205] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f0e97fff510
[15:16:45.205] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30339072 fPxarMemory = 0x7f0e91d94010
[15:16:45.206] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[15:16:45.208] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[15:16:45.208] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:16:45.699] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1e66f10
[15:16:45.761] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:16:45.761] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:16:45.761] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:16:45.767] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:16:45.767] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:16:45.767] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:16:45.776] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:16:45.776] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:16:45.785] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:16:45.785] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:16:45.808] INFO: PixTestCmd::init()
[15:16:45.817] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:16:45.817] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:16:45.817] INFO: readGainPedestalParameters data/mqa25/phCalibrationFitErr35_C0.dat .. data/mqa25/phCalibrationFitErr35_C15.dat
[15:16:46.073] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:16:46.074] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:16:46.088] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:16:46.093] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:16:46.093] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:16:46.093] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:16:46.121] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:16:46.121] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:16:46.121] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:16:46.121] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:16:46.121] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:16:46.129] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:16:46.129] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:16:46.154] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:16:46.154] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:16:46.154] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:16:46.154] INFO: readReadbackCal: data/mqa25/readbackCal_C0.dat .. data/mqa25/readbackCal_C15.dat
[15:16:46.163] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:16:46.177] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[15:16:46.177] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[15:16:46.177] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[15:16:46.177] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[15:16:46.190] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:16:46.190] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:16:50.169] INFO: ######################################################################
[15:16:50.169] INFO: PixTestAlive::doTest()
[15:16:50.169] INFO: ######################################################################
[15:16:50.172] INFO: ----------------------------------------------------------------------
[15:16:50.172] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:16:50.172] INFO: ----------------------------------------------------------------------
[15:16:50.175] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:16:50.554] INFO: Expecting 41600 events.
[15:16:54.935] INFO: 41600 events read in total (3662ms).
[15:16:55.081] INFO: Test took 4906ms.
[15:16:55.093] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:16:55.093] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[15:16:55.093] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:16:55.374] INFO: PixTestAlive::aliveTest() done with 7 decoding errors
[15:16:55.374] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:16:55.375] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 48 32 48 81 80 90 66 72 74 84 85 74 70 37 24 35
[15:16:55.401] INFO: ----------------------------------------------------------------------
[15:16:55.401] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:16:55.401] INFO: ----------------------------------------------------------------------
[15:16:55.404] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:16:55.779] INFO: Expecting 41600 events.
[15:16:58.893] INFO: 41600 events read in total (2398ms).
[15:16:58.894] INFO: Test took 3490ms.
[15:16:58.894] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:16:58.894] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:16:58.894] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:16:58.894] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:16:59.316] INFO: PixTestAlive::maskTest() done
[15:16:59.316] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:16:59.339] INFO: ----------------------------------------------------------------------
[15:16:59.339] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:16:59.339] INFO: ----------------------------------------------------------------------
[15:16:59.341] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:16:59.720] INFO: Expecting 41600 events.
[15:17:04.100] INFO: 41600 events read in total (3665ms).
[15:17:04.101] INFO: Test took 4760ms.
[15:17:04.112] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:04.112] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[15:17:04.112] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:17:04.483] INFO: PixTestAlive::addressDecodingTest() done with 4 decoding errors
[15:17:04.483] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:17:04.483] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:17:07.318] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:17:07.318] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:17:07.318] INFO: ----------------------------------------------------------------------
[15:17:07.318] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:17:07.318] INFO: ----------------------------------------------------------------------
[15:17:07.457] INFO: Expecting 768 events.
[15:17:08.591] INFO: 768 events read in total (419ms).
[15:17:08.591] INFO: Test took 1267ms.
[15:17:08.597] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:17:09.394] INFO: Expecting 41600 events.
[15:17:13.250] INFO: 41600 events read in total (3329ms).
[15:17:13.255] INFO: Test took 4658ms.
[15:17:13.407] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:13.407] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 883506
[15:17:13.407] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:17:13.408] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:17:13.472] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:17:14.137] INFO: Expecting 41600 events.
[15:17:18.098] INFO: 41600 events read in total (3434ms).
[15:17:18.104] INFO: Test took 4632ms.
[15:17:18.251] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:18.251] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 894707
[15:17:18.251] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:17:18.251] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:17:18.320] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:17:18.987] INFO: Expecting 41600 events.
[15:17:22.978] INFO: 41600 events read in total (3465ms).
[15:17:22.983] INFO: Test took 4663ms.
[15:17:23.130] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:23.130] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 894790
[15:17:23.130] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:17:23.131] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:17:23.199] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:17:23.867] INFO: Expecting 41600 events.
[15:17:27.858] INFO: 41600 events read in total (3465ms).
[15:17:27.863] INFO: Test took 4664ms.
[15:17:28.009] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:28.010] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 895103
[15:17:28.010] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:17:28.010] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:17:28.079] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:17:28.746] INFO: Expecting 41600 events.
[15:17:32.744] INFO: 41600 events read in total (3471ms).
[15:17:32.749] INFO: Test took 4670ms.
[15:17:32.895] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:32.895] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 896293
[15:17:32.895] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:17:32.896] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:17:32.965] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:17:33.632] INFO: Expecting 41600 events.
[15:17:37.634] INFO: 41600 events read in total (3475ms).
[15:17:37.640] INFO: Test took 4675ms.
[15:17:37.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:37.786] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 894359
[15:17:37.786] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:17:37.787] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:17:37.855] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:17:38.520] INFO: Expecting 41600 events.
[15:17:42.534] INFO: 41600 events read in total (3488ms).
[15:17:42.539] INFO: Test took 4684ms.
[15:17:42.687] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:42.687] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 896235
[15:17:42.687] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:17:42.688] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:17:42.757] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:17:43.424] INFO: Expecting 41600 events.
[15:17:47.439] INFO: 41600 events read in total (3488ms).
[15:17:47.444] INFO: Test took 4687ms.
[15:17:47.591] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:47.591] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 895975
[15:17:47.591] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:17:47.591] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:17:47.661] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:17:48.327] INFO: Expecting 41600 events.
[15:17:52.356] INFO: 41600 events read in total (3502ms).
[15:17:52.361] INFO: Test took 4700ms.
[15:17:52.506] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:52.506] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 898258
[15:17:52.506] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:17:52.507] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:17:52.574] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:17:53.240] INFO: Expecting 41600 events.
[15:17:57.273] INFO: 41600 events read in total (3507ms).
[15:17:57.279] INFO: Test took 4705ms.
[15:17:57.423] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:17:57.424] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 896764
[15:17:57.424] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:17:57.424] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:17:57.493] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:17:58.160] INFO: Expecting 41600 events.
[15:18:02.181] INFO: 41600 events read in total (3494ms).
[15:18:02.186] INFO: Test took 4693ms.
[15:18:02.332] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:02.332] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 896873
[15:18:02.332] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:18:02.332] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:18:02.403] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:18:03.068] INFO: Expecting 41600 events.
[15:18:07.100] INFO: 41600 events read in total (3505ms).
[15:18:07.106] INFO: Test took 4703ms.
[15:18:07.253] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:07.253] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 898902
[15:18:07.254] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:18:07.254] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:18:07.323] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:18:07.989] INFO: Expecting 41600 events.
[15:18:12.008] INFO: 41600 events read in total (3492ms).
[15:18:12.013] INFO: Test took 4690ms.
[15:18:12.158] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:12.158] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 896874
[15:18:12.158] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:18:12.158] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:18:12.226] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:18:12.891] INFO: Expecting 41600 events.
[15:18:16.899] INFO: 41600 events read in total (3481ms).
[15:18:16.905] INFO: Test took 4679ms.
[15:18:17.052] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:17.052] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 896797
[15:18:17.052] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:18:17.052] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:18:17.121] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:18:17.792] INFO: Expecting 41600 events.
[15:18:21.817] INFO: 41600 events read in total (3499ms).
[15:18:21.822] INFO: Test took 4701ms.
[15:18:21.968] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:21.968] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 896277
[15:18:21.968] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:18:21.968] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:18:22.039] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:18:22.705] INFO: Expecting 41600 events.
[15:18:26.736] INFO: 41600 events read in total (3504ms).
[15:18:26.742] INFO: Test took 4703ms.
[15:18:26.889] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:26.889] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 898598
[15:18:26.889] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:18:26.889] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:18:26.959] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:18:27.626] INFO: Expecting 41600 events.
[15:18:31.657] INFO: 41600 events read in total (3504ms).
[15:18:31.663] INFO: Test took 4704ms.
[15:18:31.808] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:31.808] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 895938
[15:18:31.808] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:18:31.808] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:18:31.877] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:18:32.542] INFO: Expecting 41600 events.
[15:18:36.565] INFO: 41600 events read in total (3497ms).
[15:18:36.571] INFO: Test took 4694ms.
[15:18:36.715] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:36.715] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 896122
[15:18:36.715] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:18:36.716] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:18:36.785] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:18:37.450] INFO: Expecting 41600 events.
[15:18:41.443] INFO: 41600 events read in total (3466ms).
[15:18:41.448] INFO: Test took 4663ms.
[15:18:41.596] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:41.596] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 896697
[15:18:41.596] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:18:41.597] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:18:41.667] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:18:42.334] INFO: Expecting 41600 events.
[15:18:46.146] INFO: 41600 events read in total (3286ms).
[15:18:46.151] INFO: Test took 4484ms.
[15:18:46.294] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:18:46.294] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 887355
[15:18:46.294] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:18:46.295] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:18:46.583] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 123 eff = 0.997356
[15:18:46.583] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 130 eff = 0.999207
[15:18:46.583] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 146 eff = 0.99738
[15:18:46.583] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 156 eff = 0.996442
[15:18:46.584] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 151 eff = 0.995288
[15:18:46.584] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 148 eff = 0.99524
[15:18:46.584] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 141 eff = 0.995841
[15:18:46.584] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 163 eff = 0.996154
[15:18:46.585] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 174 eff = 0.995433
[15:18:46.585] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 161 eff = 0.994111
[15:18:46.585] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 152 eff = 0.994784
[15:18:46.585] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 160 eff = 0.995649
[15:18:46.585] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 158 eff = 0.995144
[15:18:46.585] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 164 eff = 0.997428
[15:18:46.586] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 142 eff = 0.999087
[15:18:46.586] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 149 eff = 0.998942
[15:18:49.126] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:18:49.126] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:18:49.126] INFO: ----------------------------------------------------------------------
[15:18:49.126] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:18:49.126] INFO: ----------------------------------------------------------------------
[15:18:49.126] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:18:49.126] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:18:49.126] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:18:49.126] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:18:49.126] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:18:49.126] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:18:49.126] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:18:49.126] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:18:49.126] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:18:49.126] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:18:49.126] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:18:49.126] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:18:49.145] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:18:49.818] INFO: Expecting 208000 events.
[15:19:06.766] INFO: 208000 events read in total (16421ms).
[15:19:06.788] INFO: Test took 17643ms.
[15:19:07.584] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:19:07.584] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4170949
[15:19:07.584] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:19:07.584] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:19:08.100] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:19:08.100] INFO: number of red-efficiency pixels: 456 217 453 707 824 855 774 713 796 1000 894 772 818 466 208 243
[15:19:08.100] INFO: number of X-ray hits detected: 167664 116038 200152 301232 319291 318984 309274 291362 320142 341769 332947 301002 310761 205851 121284 146636
[15:19:08.100] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:19:08.100] INFO: number of Vcal hits detected: 207392 207774 207473 207198 207052 206963 207093 207173 207092 206773 206928 207081 207037 207463 207776 207742
[15:19:08.100] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.8 99.7 99.6 99.5 99.6 99.6 99.6 99.5 99.5 99.6 99.6 99.8 99.9 99.9
[15:19:08.100] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.7 99.6 99.5 99.5 99.6 99.6 99.6 99.4 99.5 99.6 99.5 99.7 99.9 99.9
[15:19:08.100] INFO: X-ray hit rate [MHz/cm2]: 49.1 34.0 58.7 88.3 93.6 93.5 90.7 85.4 93.8 100.2 97.6 88.2 91.1 60.3 35.5 43.0
[15:19:08.100] INFO: PixTestHighRate::doXPixelAlive() done
[15:19:08.145] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:19:08.145] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:19:08.145] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:19:08.145] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:19:08.145] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:19:08.145] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:19:08.145] INFO: PixTest:: pg_setup set to default.
[15:19:55.478] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:19:55.478] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 387.5mA
[15:19:55.479] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[15:19:55.479] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[15:19:55.479] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:19:55.479] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:19:55.479] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:19:55.480] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:19:55.480] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:19:55.480] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:19:55.480] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:19:55.480] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:19:55.480] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:19:55.480] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:19:55.563] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:19:55.563] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:19:55.573] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:19:55.573] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:19:55.573] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:19:55.573] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[15:19:55.573] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:19:55.573] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:19:55.576] QUIET: Connection to board 58 closed.
[15:19:55.655] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-904-gd94b9f2 on branch master