Test Date: 1973-11-29 16:33
Analysis date: 2016-09-30 18:03
Logfile
hrData_40.log
[12:51:20.752] INFO: *** Welcome to pxar ***
[12:51:20.752] INFO: *** Today: 2016/09/30
[12:51:20.776] INFO: *** Version: v1.9.0-818-g96727
[12:51:20.776] INFO: readRocDacs: data/mq946/dacParameters35_C0.dat .. data/mq946/dacParameters35_C15.dat
[12:51:20.777] INFO: readTbmDacs: data/mq946/tbmParameters_C0a.dat .. data/mq946/tbmParameters_C0b.dat
[12:51:20.777] INFO: readMaskFile: data/mq946/defaultMaskFile.dat
[12:51:20.777] INFO: readTrimFile: data/mq946/trimParameters35_C0.dat .. data/mq946/trimParameters35_C15.dat
[12:51:20.927] INFO: clk: 4
[12:51:20.927] INFO: ctr: 4
[12:51:20.927] INFO: sda: 19
[12:51:20.927] INFO: tin: 9
[12:51:20.927] INFO: level: 15
[12:51:20.928] INFO: triggerdelay: 0
[12:51:20.928] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:51:20.928] INFO: Log level: DEBUG
[12:51:20.938] QUIET: Connection to board DTB_WRPRHI opened.
[12:51:20.941] 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:
------------------------------------------------------
[12:51:20.944] INFO: RPC call hashes of host and DTB match: 398089610
[12:51:22.547] INFO: DUT info:
[12:51:22.547] INFO: The DUT currently contains the following objects:
[12:51:22.548] INFO: 2 TBM Cores tbm08c (2 ON)
[12:51:22.548] INFO: TBM Core alpha (0): 7 registers set
[12:51:22.548] INFO: TBM Core beta (1): 7 registers set
[12:51:22.548] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:51:22.548] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:22.548] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:51:22.548] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:51:22.548] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:51:22.549] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:51:22.550] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:51:22.551] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:51:22.552] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:51:22.554] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31535104
[12:51:22.554] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1c930e0
[12:51:22.554] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1a35370
[12:51:22.554] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f31b1d94010
[12:51:22.554] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f31b7fff510
[12:51:22.554] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31543296 fPxarMemory = 0x7f31b1d94010
[12:51:22.555] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 386.7mA
[12:51:22.556] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[12:51:22.556] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[12:51:23.067] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2082030
[12:51:23.128] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:51:23.129] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:51:23.129] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:51:23.134] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:51:23.134] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:51:23.135] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:51:23.146] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:51:23.146] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:51:23.166] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:51:23.166] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:51:23.181] INFO: PixTestCmd::init()
[12:51:23.195] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:51:23.195] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:51:23.195] INFO: readGainPedestalParameters data/mq946/phCalibrationFitErr35_C0.dat .. data/mq946/phCalibrationFitErr35_C15.dat
[12:51:23.452] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:51:23.452] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:51:23.465] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:51:23.471] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:51:23.471] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:51:23.471] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:51:23.495] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:51:23.495] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:51:23.495] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:51:23.495] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:51:23.495] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:51:23.501] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:51:23.501] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:51:23.516] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:51:23.516] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:51:23.516] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:51:23.516] INFO: readReadbackCal: data/mq946/readbackCal_C0.dat .. data/mq946/readbackCal_C15.dat
[12:51:23.526] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:51:23.536] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:51:23.536] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:51:23.536] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:51:23.536] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:51:23.555] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:51:23.555] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:51:29.070] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[12:51:29.607] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[12:51:29.607] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[12:51:29.607] INFO: ----------------------------------------------------------------------
[12:51:29.607] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[12:51:29.607] INFO: ----------------------------------------------------------------------
[12:51:30.580] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[12:51:42.993] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[12:51:42.997] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:52:10.174] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1222987 events.
[12:52:16.287] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1222987, pixels seen in all events: 5561852
[12:52:16.390] INFO: Resuming triggers.
[12:52:28.811] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[12:52:28.852] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:52:55.978] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1223674 events.
[12:53:02.111] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1223674, pixels seen in all events: 5552883
[12:53:02.295] INFO: Resuming triggers.
[12:53:14.719] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[12:53:14.760] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:53:42.049] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1224000 events.
[12:53:48.091] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1224000, pixels seen in all events: 5548667
[12:53:48.261] INFO: Resuming triggers.
[12:54:00.686] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[12:54:00.728] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:54:27.978] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1224104 events.
[12:54:34.013] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1224104, pixels seen in all events: 5547185
[12:54:34.255] INFO: Resuming triggers.
[12:54:44.650] INFO: data taking finished, elapsed time: 60 seconds.
[12:54:44.846] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:55:07.570] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1024083 events.
[12:55:12.642] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1024083, pixels seen in all events: 4642247
[12:55:12.739] INFO: PixTest:: pg_setup set to default.
[12:55:12.836] INFO: PixTestXray::doPhRun() done
[12:55:45.222] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:55:45.223] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[12:55:45.224] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[12:55:45.224] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[12:55:45.224] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:55:45.224] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:55:45.224] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:55:45.224] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:55:45.225] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:55:45.225] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:55:45.225] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:55:45.225] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:55:45.225] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:55:45.225] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:55:45.225] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:55:45.225] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:55:45.225] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:55:45.225] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:55:45.225] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:55:45.225] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:55:45.225] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:55:45.225] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:55:45.499] QUIET: Connection to board 58 closed.
[12:55:45.578] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[12:55:56.442] INFO: *** Welcome to pxar ***
[12:55:56.442] INFO: *** Today: 2016/09/30
[12:55:56.466] INFO: *** Version: v1.9.0-818-g96727
[12:55:56.467] INFO: readRocDacs: data/mq946/dacParameters35_C0.dat .. data/mq946/dacParameters35_C15.dat
[12:55:56.468] INFO: readTbmDacs: data/mq946/tbmParameters_C0a.dat .. data/mq946/tbmParameters_C0b.dat
[12:55:56.468] INFO: readMaskFile: data/mq946/defaultMaskFile.dat
[12:55:56.468] INFO: readTrimFile: data/mq946/trimParameters35_C0.dat .. data/mq946/trimParameters35_C15.dat
[12:55:56.621] INFO: clk: 4
[12:55:56.621] INFO: ctr: 4
[12:55:56.621] INFO: sda: 19
[12:55:56.621] INFO: tin: 9
[12:55:56.621] INFO: level: 15
[12:55:56.621] INFO: triggerdelay: 0
[12:55:56.621] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:55:56.621] INFO: Log level: DEBUG
[12:55:56.633] QUIET: Connection to board DTB_WRPRHI opened.
[12:55:56.637] 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:
------------------------------------------------------
[12:55:56.639] INFO: RPC call hashes of host and DTB match: 398089610
[12:55:58.243] INFO: DUT info:
[12:55:58.243] INFO: The DUT currently contains the following objects:
[12:55:58.243] INFO: 2 TBM Cores tbm08c (2 ON)
[12:55:58.243] INFO: TBM Core alpha (0): 7 registers set
[12:55:58.243] INFO: TBM Core beta (1): 7 registers set
[12:55:58.243] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:55:58.243] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.243] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.243] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.243] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.243] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.243] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.243] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.243] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.243] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.243] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.243] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.243] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.243] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.243] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.243] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.243] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:55:58.244] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:55:58.245] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:55:58.246] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:55:58.247] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:55:58.249] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35528704
[12:55:58.249] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x21510e0
[12:55:58.249] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1ef3370
[12:55:58.249] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f9db9d94010
[12:55:58.249] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f9dbffff510
[12:55:58.249] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35536896 fPxarMemory = 0x7f9db9d94010
[12:55:58.250] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 386.7mA
[12:55:58.252] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[12:55:58.252] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[12:55:58.752] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2540030
[12:55:58.814] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:55:58.814] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:55:58.814] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:55:58.821] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:55:58.821] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:55:58.821] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:55:58.834] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:55:58.835] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:55:58.856] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:55:58.856] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:55:58.870] INFO: PixTestCmd::init()
[12:55:58.880] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:55:58.880] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:55:58.880] INFO: readGainPedestalParameters data/mq946/phCalibrationFitErr35_C0.dat .. data/mq946/phCalibrationFitErr35_C15.dat
[12:55:59.130] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:55:59.130] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:55:59.143] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:55:59.150] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:55:59.150] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:55:59.150] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:55:59.174] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:55:59.174] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:55:59.174] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:55:59.174] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:55:59.174] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:55:59.181] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:55:59.181] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:55:59.203] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:55:59.203] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:55:59.203] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:55:59.203] INFO: readReadbackCal: data/mq946/readbackCal_C0.dat .. data/mq946/readbackCal_C15.dat
[12:55:59.214] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:55:59.222] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:55:59.222] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:55:59.222] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:55:59.222] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:55:59.239] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:55:59.240] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:56:01.935] INFO: ######################################################################
[12:56:01.935] INFO: PixTestXray::doTest()
[12:56:01.935] INFO: ######################################################################
[12:56:01.935] INFO: ----------------------------------------------------------------------
[12:56:01.935] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:56:01.935] INFO: ----------------------------------------------------------------------
[12:56:02.908] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:56:10.479] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[12:56:10.483] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:56:36.024] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 745912 events.
[12:56:48.159] DEBUG: <PixTestXray.cc/processData:L823> # events read: 745912, pixels seen in all events: 11273717
[12:56:48.407] INFO: Resuming triggers.
[12:56:55.986] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[12:56:56.028] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:57:21.478] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 746661 events.
[12:57:33.643] DEBUG: <PixTestXray.cc/processData:L823> # events read: 746661, pixels seen in all events: 11262500
[12:57:33.849] INFO: Resuming triggers.
[12:57:41.429] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[12:57:41.492] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:58:06.935] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 746735 events.
[12:58:19.031] DEBUG: <PixTestXray.cc/processData:L823> # events read: 746735, pixels seen in all events: 11261814
[12:58:19.270] INFO: Resuming triggers.
[12:58:26.851] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[12:58:26.891] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:58:52.316] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 746808 events.
[12:59:04.382] DEBUG: <PixTestXray.cc/processData:L823> # events read: 746808, pixels seen in all events: 11260370
[12:59:04.607] INFO: Resuming triggers.
[12:59:12.190] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[12:59:12.229] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:59:37.768] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 746918 events.
[12:59:49.838] DEBUG: <PixTestXray.cc/processData:L823> # events read: 746918, pixels seen in all events: 11259866
[12:59:50.033] INFO: Resuming triggers.
[12:59:57.613] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[12:59:57.674] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:00:22.975] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 746710 events.
[13:00:35.080] DEBUG: <PixTestXray.cc/processData:L823> # events read: 746710, pixels seen in all events: 11261974
[13:00:35.304] INFO: Resuming triggers.
[13:00:42.891] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[13:00:42.933] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:01:08.413] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 747305 events.
[13:01:20.529] DEBUG: <PixTestXray.cc/processData:L823> # events read: 747305, pixels seen in all events: 11254552
[13:01:20.696] INFO: Resuming triggers.
[13:01:28.282] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[13:01:28.380] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:01:53.774] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 747387 events.
[13:02:05.873] DEBUG: <PixTestXray.cc/processData:L823> # events read: 747387, pixels seen in all events: 11254311
[13:02:06.102] INFO: Resuming triggers.
[13:02:13.684] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[13:02:13.726] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:02:39.161] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 746918 events.
[13:02:51.228] DEBUG: <PixTestXray.cc/processData:L823> # events read: 746918, pixels seen in all events: 11260269
[13:02:51.456] INFO: Resuming triggers.
[13:02:59.041] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[13:02:59.083] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:03:24.464] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 747277 events.
[13:03:36.589] DEBUG: <PixTestXray.cc/processData:L823> # events read: 747277, pixels seen in all events: 11255157
[13:03:36.818] INFO: Resuming triggers.
[13:03:44.403] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[13:03:44.441] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:04:09.904] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 747276 events.
[13:04:21.968] DEBUG: <PixTestXray.cc/processData:L823> # events read: 747276, pixels seen in all events: 11255920
[13:04:22.193] INFO: Resuming triggers.
[13:04:29.776] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[13:04:29.819] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:04:55.332] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 747030 events.
[13:05:07.425] DEBUG: <PixTestXray.cc/processData:L823> # events read: 747030, pixels seen in all events: 11258761
[13:05:07.655] INFO: Resuming triggers.
[13:05:15.243] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[13:05:15.285] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:05:40.727] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 747499 events.
[13:05:52.793] DEBUG: <PixTestXray.cc/processData:L823> # events read: 747499, pixels seen in all events: 11253702
[13:05:52.991] INFO: Resuming triggers.
[13:05:54.547] INFO: data taking finished, elapsed time: 100 seconds.
[13:05:54.743] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[13:06:00.146] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 153257 events.
[13:06:02.620] DEBUG: <PixTestXray.cc/processData:L823> # events read: 153257, pixels seen in all events: 2307467
[13:06:02.684] INFO: PixTest:: pg_setup set to default.
[13:06:02.726] INFO: PixTestXray::doPhRun() done
[13:06:02.726] INFO: PixTestXray::doTest() done
[13:06:09.175] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[13:06:09.176] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[13:06:09.176] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 470.3mA
[13:06:09.176] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[13:06:09.176] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[13:06:09.176] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[13:06:09.177] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[13:06:09.177] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[13:06:09.177] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[13:06:09.177] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[13:06:09.177] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[13:06:09.177] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[13:06:09.177] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[13:06:09.177] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[13:06:09.177] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[13:06:09.177] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[13:06:09.177] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[13:06:09.177] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[13:06:09.177] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[13:06:09.178] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[13:06:09.178] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[13:06:09.178] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[13:06:09.497] QUIET: Connection to board 58 closed.
[13:06:09.577] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[12:45:24.840] INFO: *** Welcome to pxar ***
[12:45:24.840] INFO: *** Today: 2016/09/30
[12:45:24.864] INFO: *** Version: v1.9.0-818-g96727
[12:45:24.864] INFO: readRocDacs: data/mq946/dacParameters35_C0.dat .. data/mq946/dacParameters35_C15.dat
[12:45:24.865] INFO: readTbmDacs: data/mq946/tbmParameters_C0a.dat .. data/mq946/tbmParameters_C0b.dat
[12:45:24.866] INFO: readMaskFile: data/mq946/defaultMaskFile.dat
[12:45:24.866] INFO: readTrimFile: data/mq946/trimParameters35_C0.dat .. data/mq946/trimParameters35_C15.dat
[12:45:25.016] INFO: clk: 4
[12:45:25.016] INFO: ctr: 4
[12:45:25.016] INFO: sda: 19
[12:45:25.016] INFO: tin: 9
[12:45:25.016] INFO: level: 15
[12:45:25.016] INFO: triggerdelay: 0
[12:45:25.016] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:45:25.016] INFO: Log level: DEBUG
[12:45:25.027] QUIET: Connection to board DTB_WRPRHI opened.
[12:45:25.030] 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:
------------------------------------------------------
[12:45:25.033] INFO: RPC call hashes of host and DTB match: 398089610
[12:45:26.636] INFO: DUT info:
[12:45:26.636] INFO: The DUT currently contains the following objects:
[12:45:26.636] INFO: 2 TBM Cores tbm08c (2 ON)
[12:45:26.636] INFO: TBM Core alpha (0): 7 registers set
[12:45:26.636] INFO: TBM Core beta (1): 7 registers set
[12:45:26.636] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:45:26.636] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.636] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.636] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.636] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.636] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.636] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.636] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.636] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.636] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.636] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.636] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.636] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.636] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.636] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.636] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.636] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:45:26.637] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:45:26.638] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:45:26.639] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:45:26.640] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:45:26.642] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33239040
[12:45:26.642] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x15220e0
[12:45:26.642] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x12c4370
[12:45:26.642] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7efe65d94010
[12:45:26.642] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7efe6bfff510
[12:45:26.642] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33247232 fPxarMemory = 0x7efe65d94010
[12:45:26.643] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.9mA
[12:45:26.645] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[12:45:26.645] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[12:45:27.142] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1910fd0
[12:45:27.206] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:45:27.206] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:45:27.206] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:45:27.212] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:45:27.212] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:45:27.212] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:45:27.223] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:45:27.223] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:45:27.240] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:45:27.240] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:45:27.256] INFO: PixTestCmd::init()
[12:45:27.276] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:45:27.277] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:45:27.277] INFO: readGainPedestalParameters data/mq946/phCalibrationFitErr35_C0.dat .. data/mq946/phCalibrationFitErr35_C15.dat
[12:45:27.524] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:45:27.524] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:45:27.538] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:45:27.544] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:45:27.544] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:45:27.544] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:45:27.568] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:45:27.568] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:45:27.568] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:45:27.568] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:45:27.568] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:45:27.575] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:45:27.575] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:45:27.595] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:45:27.595] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:45:27.596] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:45:27.596] INFO: readReadbackCal: data/mq946/readbackCal_C0.dat .. data/mq946/readbackCal_C15.dat
[12:45:27.605] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:45:27.613] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:45:27.613] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:45:27.613] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:45:27.613] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:45:27.632] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:45:27.632] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:45:32.769] INFO: ######################################################################
[12:45:32.770] INFO: PixTestAlive::doTest()
[12:45:32.770] INFO: ######################################################################
[12:45:32.773] INFO: ----------------------------------------------------------------------
[12:45:32.773] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:45:32.773] INFO: ----------------------------------------------------------------------
[12:45:32.775] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:45:33.157] INFO: Expecting 41600 events.
[12:45:37.568] INFO: 41600 events read in total (3693ms).
[12:45:37.715] INFO: Test took 4939ms.
[12:45:37.726] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:37.726] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[12:45:37.726] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[12:45:38.003] INFO: PixTestAlive::aliveTest() done
[12:45:38.004] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 2 0 0 0 0 0 0 0 0
[12:45:38.004] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 9 12 17 30 26 41 40 39 30 37 35 32 38 30 17 17
[12:45:38.029] INFO: ----------------------------------------------------------------------
[12:45:38.029] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:45:38.029] INFO: ----------------------------------------------------------------------
[12:45:38.031] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:45:38.410] INFO: Expecting 41600 events.
[12:45:41.532] INFO: 41600 events read in total (2407ms).
[12:45:41.532] INFO: Test took 3500ms.
[12:45:41.532] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:41.532] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[12:45:41.532] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[12:45:41.533] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:45:41.955] INFO: PixTestAlive::maskTest() done
[12:45:41.955] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:45:41.977] INFO: ----------------------------------------------------------------------
[12:45:41.977] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:45:41.977] INFO: ----------------------------------------------------------------------
[12:45:41.979] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:45:42.356] INFO: Expecting 41600 events.
[12:45:46.748] INFO: 41600 events read in total (3677ms).
[12:45:46.750] INFO: Test took 4771ms.
[12:45:46.761] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:46.761] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[12:45:46.761] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[12:45:47.132] INFO: PixTestAlive::addressDecodingTest() done
[12:45:47.132] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:45:47.132] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[12:45:57.542] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:45:57.542] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[12:45:59.854] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[12:45:59.854] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[12:45:59.854] INFO: ----------------------------------------------------------------------
[12:45:59.854] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:45:59.854] INFO: ----------------------------------------------------------------------
[12:45:59.994] INFO: Expecting 768 events.
[12:46:01.128] INFO: 768 events read in total (419ms).
[12:46:01.128] INFO: Test took 1268ms.
[12:46:01.134] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:46:01.932] INFO: Expecting 41600 events.
[12:46:05.318] INFO: 41600 events read in total (2859ms).
[12:46:05.323] INFO: Test took 4189ms.
[12:46:05.405] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:05.405] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 467517
[12:46:05.405] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[12:46:05.405] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:46:05.445] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:46:06.113] INFO: Expecting 41600 events.
[12:46:09.583] INFO: 41600 events read in total (2944ms).
[12:46:09.586] INFO: Test took 4141ms.
[12:46:09.670] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:09.671] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 474136
[12:46:09.671] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[12:46:09.671] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:46:09.710] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:46:10.376] INFO: Expecting 41600 events.
[12:46:13.887] INFO: 41600 events read in total (2985ms).
[12:46:13.891] INFO: Test took 4180ms.
[12:46:13.969] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:13.969] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 475701
[12:46:13.969] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[12:46:13.970] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:46:14.009] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:46:14.674] INFO: Expecting 41600 events.
[12:46:18.178] INFO: 41600 events read in total (2977ms).
[12:46:18.182] INFO: Test took 4173ms.
[12:46:18.261] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:18.261] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477045
[12:46:18.261] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[12:46:18.261] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:46:18.300] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:46:18.965] INFO: Expecting 41600 events.
[12:46:22.496] INFO: 41600 events read in total (3004ms).
[12:46:22.500] INFO: Test took 4200ms.
[12:46:22.578] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:22.578] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477074
[12:46:22.578] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[12:46:22.578] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:46:22.617] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:46:23.283] INFO: Expecting 41600 events.
[12:46:26.813] INFO: 41600 events read in total (3003ms).
[12:46:26.817] INFO: Test took 4199ms.
[12:46:26.895] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:26.895] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477159
[12:46:26.895] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[12:46:26.895] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:46:26.935] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:46:27.600] INFO: Expecting 41600 events.
[12:46:31.140] INFO: 41600 events read in total (3014ms).
[12:46:31.143] INFO: Test took 4208ms.
[12:46:31.223] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:31.223] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476836
[12:46:31.223] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[12:46:31.223] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:46:31.264] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:46:31.931] INFO: Expecting 41600 events.
[12:46:35.472] INFO: 41600 events read in total (3014ms).
[12:46:35.476] INFO: Test took 4212ms.
[12:46:35.554] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:35.554] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 478472
[12:46:35.554] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[12:46:35.555] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:46:35.594] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:46:36.259] INFO: Expecting 41600 events.
[12:46:39.795] INFO: 41600 events read in total (3009ms).
[12:46:39.799] INFO: Test took 4205ms.
[12:46:39.880] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:39.880] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 475851
[12:46:39.880] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[12:46:39.880] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:46:39.921] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:46:40.588] INFO: Expecting 41600 events.
[12:46:44.129] INFO: 41600 events read in total (3014ms).
[12:46:44.132] INFO: Test took 4210ms.
[12:46:44.213] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:44.213] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477507
[12:46:44.213] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[12:46:44.213] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:46:44.255] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:46:44.922] INFO: Expecting 41600 events.
[12:46:48.458] INFO: 41600 events read in total (3010ms).
[12:46:48.461] INFO: Test took 4206ms.
[12:46:48.545] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:48.545] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477826
[12:46:48.545] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[12:46:48.545] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:46:48.586] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:46:49.253] INFO: Expecting 41600 events.
[12:46:52.794] INFO: 41600 events read in total (3014ms).
[12:46:52.798] INFO: Test took 4211ms.
[12:46:52.878] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:52.879] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477149
[12:46:52.879] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[12:46:52.879] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:46:52.920] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:46:53.588] INFO: Expecting 41600 events.
[12:46:57.127] INFO: 41600 events read in total (3012ms).
[12:46:57.131] INFO: Test took 4211ms.
[12:46:57.212] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:57.212] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476196
[12:46:57.212] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[12:46:57.212] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:46:57.254] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:46:57.920] INFO: Expecting 41600 events.
[12:47:01.464] INFO: 41600 events read in total (3017ms).
[12:47:01.468] INFO: Test took 4214ms.
[12:47:01.547] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:01.547] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477877
[12:47:01.547] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[12:47:01.548] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:47:01.589] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:47:02.257] INFO: Expecting 41600 events.
[12:47:05.791] INFO: 41600 events read in total (3008ms).
[12:47:05.794] INFO: Test took 4205ms.
[12:47:05.874] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:05.874] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476631
[12:47:05.874] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[12:47:05.874] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:47:05.913] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:47:06.579] INFO: Expecting 41600 events.
[12:47:10.121] INFO: 41600 events read in total (3015ms).
[12:47:10.125] INFO: Test took 4211ms.
[12:47:10.203] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:10.203] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477050
[12:47:10.203] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[12:47:10.204] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:47:10.244] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:47:10.909] INFO: Expecting 41600 events.
[12:47:14.451] INFO: 41600 events read in total (3015ms).
[12:47:14.454] INFO: Test took 4210ms.
[12:47:14.533] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:14.533] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476948
[12:47:14.533] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[12:47:14.533] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:47:14.573] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:47:15.238] INFO: Expecting 41600 events.
[12:47:18.777] INFO: 41600 events read in total (3012ms).
[12:47:18.780] INFO: Test took 4207ms.
[12:47:18.860] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:18.860] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 477358
[12:47:18.860] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[12:47:18.861] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:47:18.902] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:47:19.569] INFO: Expecting 41600 events.
[12:47:23.081] INFO: 41600 events read in total (2985ms).
[12:47:23.085] INFO: Test took 4183ms.
[12:47:23.165] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:23.165] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 476448
[12:47:23.165] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[12:47:23.165] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:47:23.207] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:47:23.873] INFO: Expecting 41600 events.
[12:47:27.226] INFO: 41600 events read in total (2826ms).
[12:47:27.230] INFO: Test took 4023ms.
[12:47:27.308] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:27.308] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469586
[12:47:27.308] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[12:47:27.309] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:47:27.570] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 149 eff = 0.999543
[12:47:27.570] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 154 eff = 0.999736
[12:47:27.571] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 150 eff = 0.999303
[12:47:27.571] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 149 eff = 0.999063
[12:47:27.571] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 143 eff = 0.998798
[12:47:27.571] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 152 eff = 0.99899
[12:47:27.572] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 158 eff = 0.998173
[12:47:27.572] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 138 eff = 0.998702
[12:47:27.572] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 173 eff = 0.999063
[12:47:27.572] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 151 eff = 0.99863
[12:47:27.572] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 159 eff = 0.998894
[12:47:27.573] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 144 eff = 0.99899
[12:47:27.573] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 132 eff = 0.995264
[12:47:27.573] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 196 eff = 0.999495
[12:47:27.573] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 167 eff = 0.999856
[12:47:27.573] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 137 eff = 0.999688
[12:47:35.702] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[12:47:35.702] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[12:47:35.702] INFO: ----------------------------------------------------------------------
[12:47:35.702] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:47:35.702] INFO: ----------------------------------------------------------------------
[12:47:35.703] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[12:47:35.703] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[12:47:35.703] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[12:47:35.703] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[12:47:35.703] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[12:47:35.703] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[12:47:35.703] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[12:47:35.703] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[12:47:35.703] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[12:47:35.703] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[12:47:35.703] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[12:47:35.703] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[12:47:35.720] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:47:36.388] INFO: Expecting 208000 events.
[12:47:50.069] INFO: 208000 events read in total (13154ms).
[12:47:50.081] INFO: Test took 14361ms.
[12:47:50.499] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:50.499] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2106087
[12:47:50.499] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[12:47:50.499] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:47:50.877] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 2 0 0 0 0 0 0 0 0
[12:47:50.877] INFO: number of red-efficiency pixels: 104 64 174 235 236 264 241 279 225 252 265 253 408 147 65 93
[12:47:50.877] INFO: number of X-ray hits detected: 88983 59354 101600 147657 161345 162428 159296 147977 147162 167317 162725 150942 144946 101335 61071 75393
[12:47:50.877] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:47:50.877] INFO: number of Vcal hits detected: 207894 207936 207818 207762 207750 207729 207627 207610 207772 207738 207722 207732 207568 207850 207933 207905
[12:47:50.877] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 100.0 100.0
[12:47:50.877] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 99.9 99.8 99.9 100.0 100.0
[12:47:50.877] INFO: X-ray hit rate [MHz/cm2]: 26.1 17.4 29.8 43.3 47.3 47.6 46.7 43.4 43.1 49.0 47.7 44.2 42.5 29.7 17.9 22.1
[12:47:50.877] INFO: PixTestHighRate::doXPixelAlive() done
[12:47:50.922] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[12:47:50.922] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[12:47:50.922] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[12:47:50.922] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[12:47:50.922] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[12:47:50.923] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[12:47:50.923] INFO: PixTest:: pg_setup set to default.
[12:47:59.638] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:47:59.638] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[12:48:01.358] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:48:01.359] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[12:48:01.360] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[12:48:01.360] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[12:48:01.360] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:48:01.360] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:48:01.360] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:48:01.360] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:48:01.360] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:48:01.360] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:48:01.360] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:48:01.360] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:48:01.360] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:48:01.360] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:48:01.453] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:48:01.453] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:48:01.467] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:48:01.467] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:48:01.467] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:48:01.467] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:48:01.467] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:48:01.467] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:48:01.469] QUIET: Connection to board 58 closed.
[12:48:01.548] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[12:42:24.186] INFO: *** Welcome to pxar ***
[12:42:24.187] INFO: *** Today: 2016/09/30
[12:42:24.210] INFO: *** Version: v1.9.0-818-g96727
[12:42:24.210] INFO: readRocDacs: data/mq946/dacParameters35_C0.dat .. data/mq946/dacParameters35_C15.dat
[12:42:24.211] INFO: readTbmDacs: data/mq946/tbmParameters_C0a.dat .. data/mq946/tbmParameters_C0b.dat
[12:42:24.211] INFO: readMaskFile: data/mq946/defaultMaskFile.dat
[12:42:24.211] INFO: readTrimFile: data/mq946/trimParameters35_C0.dat .. data/mq946/trimParameters35_C15.dat
[12:42:24.361] INFO: clk: 4
[12:42:24.361] INFO: ctr: 4
[12:42:24.361] INFO: sda: 19
[12:42:24.361] INFO: tin: 9
[12:42:24.361] INFO: level: 15
[12:42:24.361] INFO: triggerdelay: 0
[12:42:24.362] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:42:24.362] INFO: Log level: DEBUG
[12:42:24.373] QUIET: Connection to board DTB_WRPRHI opened.
[12:42:24.377] 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:
------------------------------------------------------
[12:42:24.379] INFO: RPC call hashes of host and DTB match: 398089610
[12:42:25.983] INFO: DUT info:
[12:42:25.983] INFO: The DUT currently contains the following objects:
[12:42:25.983] INFO: 2 TBM Cores tbm08c (2 ON)
[12:42:25.983] INFO: TBM Core alpha (0): 7 registers set
[12:42:25.983] INFO: TBM Core beta (1): 7 registers set
[12:42:25.983] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:42:25.983] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.983] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.983] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.983] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.983] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.983] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.983] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.983] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.984] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.984] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.984] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.984] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.984] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.984] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.984] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.984] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:42:25.984] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:42:25.985] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:42:25.986] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:42:25.987] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:42:25.989] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35532800
[12:42:25.989] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x295b0e0
[12:42:25.989] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x26fd370
[12:42:25.990] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fb27dd94010
[12:42:25.990] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fb283fff510
[12:42:25.990] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35540992 fPxarMemory = 0x7fb27dd94010
[12:42:25.991] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 386.7mA
[12:42:25.992] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[12:42:25.992] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[12:42:26.493] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2d49fd0
[12:42:26.556] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:42:26.556] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:42:26.556] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:42:26.562] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:42:26.562] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:42:26.562] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:42:26.573] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:42:26.573] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:42:26.587] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:42:26.587] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:42:26.605] INFO: PixTestCmd::init()
[12:42:26.615] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:42:26.615] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:42:26.615] INFO: readGainPedestalParameters data/mq946/phCalibrationFitErr35_C0.dat .. data/mq946/phCalibrationFitErr35_C15.dat
[12:42:26.867] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:42:26.867] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:42:26.880] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:42:26.886] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:42:26.886] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:42:26.886] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:42:26.909] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:42:26.909] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:42:26.909] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:42:26.909] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:42:26.909] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:42:26.916] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:42:26.916] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:42:26.933] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:42:26.933] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:42:26.933] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:42:26.933] INFO: readReadbackCal: data/mq946/readbackCal_C0.dat .. data/mq946/readbackCal_C15.dat
[12:42:26.944] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:42:26.953] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:42:26.953] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:42:26.953] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:42:26.953] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:42:26.973] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:42:26.974] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:42:31.010] INFO: ######################################################################
[12:42:31.010] INFO: PixTestAlive::doTest()
[12:42:31.010] INFO: ######################################################################
[12:42:31.013] INFO: ----------------------------------------------------------------------
[12:42:31.013] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:42:31.013] INFO: ----------------------------------------------------------------------
[12:42:31.016] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:42:31.395] INFO: Expecting 41600 events.
[12:42:35.808] INFO: 41600 events read in total (3695ms).
[12:42:35.955] INFO: Test took 4939ms.
[12:42:35.966] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:35.966] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[12:42:35.966] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[12:42:36.243] INFO: PixTestAlive::aliveTest() done
[12:42:36.244] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 2 0 0 0 0 0 0 0 0
[12:42:36.244] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 35 13 34 45 63 59 46 66 50 56 53 63 51 45 25 19
[12:42:36.272] INFO: ----------------------------------------------------------------------
[12:42:36.272] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:42:36.272] INFO: ----------------------------------------------------------------------
[12:42:36.275] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:42:36.655] INFO: Expecting 41600 events.
[12:42:39.770] INFO: 41600 events read in total (2400ms).
[12:42:39.770] INFO: Test took 3495ms.
[12:42:39.770] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:39.770] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[12:42:39.770] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[12:42:39.771] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:42:40.193] INFO: PixTestAlive::maskTest() done
[12:42:40.193] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:42:40.215] INFO: ----------------------------------------------------------------------
[12:42:40.215] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:42:40.215] INFO: ----------------------------------------------------------------------
[12:42:40.218] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:42:40.595] INFO: Expecting 41600 events.
[12:42:44.988] INFO: 41600 events read in total (3678ms).
[12:42:44.989] INFO: Test took 4771ms.
[12:42:45.001] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:45.001] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[12:42:45.001] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[12:42:45.373] INFO: PixTestAlive::addressDecodingTest() done
[12:42:45.373] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:42:45.373] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[12:43:08.510] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:43:08.510] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C3_V0 -> PixelAlive_mod
[12:43:08.662] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:43:08.662] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_mod_V0 -> PixelAlive_mod_V0_mod
[12:43:09.566] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:43:09.566] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_mod_V0_mod_V0 -> PixelAlive_mod_V0_mod_V0_mod
[12:43:14.022] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[12:43:14.022] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[12:43:14.022] INFO: ----------------------------------------------------------------------
[12:43:14.022] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:43:14.022] INFO: ----------------------------------------------------------------------
[12:43:14.162] INFO: Expecting 768 events.
[12:43:15.296] INFO: 768 events read in total (419ms).
[12:43:15.296] INFO: Test took 1268ms.
[12:43:15.302] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:43:16.100] INFO: Expecting 41600 events.
[12:43:19.725] INFO: 41600 events read in total (3099ms).
[12:43:19.729] INFO: Test took 4427ms.
[12:43:19.872] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:19.872] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 678288
[12:43:19.872] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[12:43:19.873] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:43:19.924] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:43:20.589] INFO: Expecting 41600 events.
[12:43:24.340] INFO: 41600 events read in total (3224ms).
[12:43:24.345] INFO: Test took 4421ms.
[12:43:24.491] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:24.491] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685347
[12:43:24.491] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[12:43:24.491] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:43:24.546] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:43:25.212] INFO: Expecting 41600 events.
[12:43:28.982] INFO: 41600 events read in total (3243ms).
[12:43:28.986] INFO: Test took 4440ms.
[12:43:29.100] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:29.100] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688695
[12:43:29.100] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[12:43:29.101] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:43:29.155] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:43:29.819] INFO: Expecting 41600 events.
[12:43:33.597] INFO: 41600 events read in total (3251ms).
[12:43:33.602] INFO: Test took 4447ms.
[12:43:33.720] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:33.720] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 689150
[12:43:33.720] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[12:43:33.721] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:43:33.776] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:43:34.443] INFO: Expecting 41600 events.
[12:43:38.228] INFO: 41600 events read in total (3258ms).
[12:43:38.232] INFO: Test took 4456ms.
[12:43:38.350] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:38.350] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688517
[12:43:38.350] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[12:43:38.350] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:43:38.405] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:43:39.072] INFO: Expecting 41600 events.
[12:43:42.858] INFO: 41600 events read in total (3260ms).
[12:43:42.863] INFO: Test took 4458ms.
[12:43:42.980] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:42.980] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 687945
[12:43:42.980] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[12:43:42.980] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:43:43.037] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:43:43.703] INFO: Expecting 41600 events.
[12:43:47.500] INFO: 41600 events read in total (3270ms).
[12:43:47.505] INFO: Test took 4468ms.
[12:43:47.620] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:47.620] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688758
[12:43:47.620] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[12:43:47.621] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:43:47.676] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:43:48.343] INFO: Expecting 41600 events.
[12:43:52.144] INFO: 41600 events read in total (3274ms).
[12:43:52.148] INFO: Test took 4472ms.
[12:43:52.267] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:52.267] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 689127
[12:43:52.267] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[12:43:52.267] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:43:52.321] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:43:52.985] INFO: Expecting 41600 events.
[12:43:56.790] INFO: 41600 events read in total (3278ms).
[12:43:56.795] INFO: Test took 4474ms.
[12:43:56.936] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:56.936] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 689870
[12:43:56.936] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[12:43:56.937] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:43:56.991] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:43:57.656] INFO: Expecting 41600 events.
[12:44:01.463] INFO: 41600 events read in total (3281ms).
[12:44:01.467] INFO: Test took 4476ms.
[12:44:01.584] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:01.584] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688306
[12:44:01.584] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[12:44:01.584] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:44:01.640] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:44:02.306] INFO: Expecting 41600 events.
[12:44:06.113] INFO: 41600 events read in total (3280ms).
[12:44:06.117] INFO: Test took 4477ms.
[12:44:06.233] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:06.233] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 686626
[12:44:06.233] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[12:44:06.233] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:44:06.288] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:44:06.953] INFO: Expecting 41600 events.
[12:44:10.759] INFO: 41600 events read in total (3279ms).
[12:44:10.764] INFO: Test took 4476ms.
[12:44:10.880] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:10.880] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688214
[12:44:10.880] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[12:44:10.881] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:44:10.935] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:44:11.600] INFO: Expecting 41600 events.
[12:44:15.406] INFO: 41600 events read in total (3279ms).
[12:44:15.410] INFO: Test took 4475ms.
[12:44:15.528] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:15.528] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688368
[12:44:15.528] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[12:44:15.528] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:44:15.584] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:44:16.251] INFO: Expecting 41600 events.
[12:44:20.058] INFO: 41600 events read in total (3280ms).
[12:44:20.063] INFO: Test took 4479ms.
[12:44:20.178] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:20.178] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 687627
[12:44:20.178] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[12:44:20.178] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:44:20.232] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:44:20.898] INFO: Expecting 41600 events.
[12:44:24.707] INFO: 41600 events read in total (3282ms).
[12:44:24.711] INFO: Test took 4479ms.
[12:44:24.827] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:24.827] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688352
[12:44:24.827] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[12:44:24.827] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:44:24.883] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:44:25.550] INFO: Expecting 41600 events.
[12:44:29.360] INFO: 41600 events read in total (3283ms).
[12:44:29.365] INFO: Test took 4481ms.
[12:44:29.481] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:29.481] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 687588
[12:44:29.481] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[12:44:29.482] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:44:29.537] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:44:30.203] INFO: Expecting 41600 events.
[12:44:34.007] INFO: 41600 events read in total (3277ms).
[12:44:34.011] INFO: Test took 4474ms.
[12:44:34.128] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:34.128] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 687750
[12:44:34.128] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[12:44:34.128] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:44:34.184] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:44:34.851] INFO: Expecting 41600 events.
[12:44:38.656] INFO: 41600 events read in total (3278ms).
[12:44:38.661] INFO: Test took 4477ms.
[12:44:38.813] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:38.813] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 689087
[12:44:38.813] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[12:44:38.813] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:44:38.868] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:44:39.535] INFO: Expecting 41600 events.
[12:44:43.306] INFO: 41600 events read in total (3244ms).
[12:44:43.311] INFO: Test took 4443ms.
[12:44:43.427] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:43.427] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 687746
[12:44:43.427] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[12:44:43.427] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:44:43.481] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:44:44.146] INFO: Expecting 41600 events.
[12:44:47.746] INFO: 41600 events read in total (3073ms).
[12:44:47.751] INFO: Test took 4270ms.
[12:44:47.863] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:47.863] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 679321
[12:44:47.863] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[12:44:47.864] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:44:48.140] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 149 eff = 0.999207
[12:44:48.140] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 145 eff = 0.999567
[12:44:48.140] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 153 eff = 0.998534
[12:44:48.140] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 167 eff = 0.998029
[12:44:48.141] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 144 eff = 0.997476
[12:44:48.141] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 153 eff = 0.99738
[12:44:48.141] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 159 eff = 0.996971
[12:44:48.141] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 147 eff = 0.997308
[12:44:48.141] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 177 eff = 0.997957
[12:44:48.142] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 157 eff = 0.996995
[12:44:48.142] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 159 eff = 0.9975
[12:44:48.142] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 163 eff = 0.998149
[12:44:48.142] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 146 eff = 0.994495
[12:44:48.142] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 193 eff = 0.998774
[12:44:48.142] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 167 eff = 0.999423
[12:44:48.142] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 141 eff = 0.999375
[12:44:52.102] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[12:44:52.102] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[12:44:52.102] INFO: ----------------------------------------------------------------------
[12:44:52.102] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:44:52.102] INFO: ----------------------------------------------------------------------
[12:44:52.103] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[12:44:52.103] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[12:44:52.103] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[12:44:52.103] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[12:44:52.103] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[12:44:52.103] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[12:44:52.103] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[12:44:52.103] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[12:44:52.103] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[12:44:52.103] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[12:44:52.103] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[12:44:52.103] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[12:44:52.120] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:44:52.792] INFO: Expecting 208000 events.
[12:45:08.032] INFO: 208000 events read in total (14713ms).
[12:45:08.048] INFO: Test took 15928ms.
[12:45:08.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:08.645] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3140756
[12:45:08.645] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[12:45:08.646] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:45:09.088] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 2 0 0 0 0 0 0 0 0
[12:45:09.088] INFO: number of red-efficiency pixels: 222 142 264 395 495 483 540 434 418 553 507 411 561 242 131 163
[12:45:09.088] INFO: number of X-ray hits detected: 134712 89461 152380 221954 243810 245719 239107 222997 221412 251443 244668 227735 219409 153384 92076 113933
[12:45:09.088] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:45:09.088] INFO: number of Vcal hits detected: 207768 207855 207717 207577 207457 207472 207179 207449 207556 207327 207443 207558 207362 207735 207866 207829
[12:45:09.088] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.8 99.7 99.7 99.8 99.7 99.9 99.9 99.9
[12:45:09.088] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.6 99.7 99.8 99.7 99.7 99.8 99.7 99.9 99.9 99.9
[12:45:09.088] INFO: X-ray hit rate [MHz/cm2]: 39.5 26.2 44.7 65.1 71.5 72.0 70.1 65.4 64.9 73.7 71.7 66.8 64.3 45.0 27.0 33.4
[12:45:09.088] INFO: PixTestHighRate::doXPixelAlive() done
[12:45:09.133] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[12:45:09.133] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[12:45:09.133] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[12:45:09.133] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[12:45:09.133] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[12:45:09.133] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[12:45:09.133] INFO: PixTest:: pg_setup set to default.
[12:45:17.238] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:45:17.239] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C9_V0 -> highRate_xraymap_mod
[12:45:19.046] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:45:19.047] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[12:45:19.048] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[12:45:19.048] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[12:45:19.048] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:45:19.048] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:45:19.048] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:45:19.048] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:45:19.048] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:45:19.048] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:45:19.048] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:45:19.048] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:45:19.049] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:45:19.049] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:45:19.141] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:45:19.142] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:45:19.163] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:45:19.163] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:45:19.163] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:45:19.163] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:45:19.163] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:45:19.163] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:45:19.166] QUIET: Connection to board 58 closed.
[12:45:19.245] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[12:39:16.478] INFO: *** Welcome to pxar ***
[12:39:16.478] INFO: *** Today: 2016/09/30
[12:39:16.501] INFO: *** Version: v1.9.0-818-g96727
[12:39:16.501] INFO: readRocDacs: data/mq946/dacParameters35_C0.dat .. data/mq946/dacParameters35_C15.dat
[12:39:16.502] INFO: readTbmDacs: data/mq946/tbmParameters_C0a.dat .. data/mq946/tbmParameters_C0b.dat
[12:39:16.502] INFO: readMaskFile: data/mq946/defaultMaskFile.dat
[12:39:16.502] INFO: readTrimFile: data/mq946/trimParameters35_C0.dat .. data/mq946/trimParameters35_C15.dat
[12:39:16.652] INFO: clk: 4
[12:39:16.652] INFO: ctr: 4
[12:39:16.652] INFO: sda: 19
[12:39:16.652] INFO: tin: 9
[12:39:16.652] INFO: level: 15
[12:39:16.652] INFO: triggerdelay: 0
[12:39:16.652] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:39:16.652] INFO: Log level: DEBUG
[12:39:16.664] QUIET: Connection to board DTB_WRPRHI opened.
[12:39:16.667] 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:
------------------------------------------------------
[12:39:16.670] INFO: RPC call hashes of host and DTB match: 398089610
[12:39:18.277] INFO: DUT info:
[12:39:18.277] INFO: The DUT currently contains the following objects:
[12:39:18.277] INFO: 2 TBM Cores tbm08c (2 ON)
[12:39:18.277] INFO: TBM Core alpha (0): 7 registers set
[12:39:18.277] INFO: TBM Core beta (1): 7 registers set
[12:39:18.278] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:39:18.278] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:18.278] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:39:18.278] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:39:18.278] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:39:18.278] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:39:18.278] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:39:18.278] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:39:18.279] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:39:18.280] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:39:18.281] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:39:18.283] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34811904
[12:39:18.283] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2a860e0
[12:39:18.283] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2828370
[12:39:18.283] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fc741d94010
[12:39:18.283] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fc747fff510
[12:39:18.283] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34820096 fPxarMemory = 0x7fc741d94010
[12:39:18.285] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 386.7mA
[12:39:18.286] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 468.7mA
[12:39:18.286] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[12:39:18.787] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2e74f60
[12:39:18.851] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:39:18.851] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:39:18.851] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:39:18.857] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:39:18.857] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:39:18.857] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:39:18.866] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:39:18.866] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:39:18.874] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:39:18.874] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:39:18.891] INFO: PixTestCmd::init()
[12:39:18.903] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:39:18.903] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:39:18.903] INFO: readGainPedestalParameters data/mq946/phCalibrationFitErr35_C0.dat .. data/mq946/phCalibrationFitErr35_C15.dat
[12:39:19.150] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:39:19.150] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:39:19.164] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:39:19.171] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:39:19.171] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:39:19.171] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:39:19.198] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:39:19.198] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:39:19.198] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:39:19.198] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:39:19.198] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:39:19.206] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:39:19.206] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:39:19.226] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:39:19.226] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:39:19.226] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:39:19.226] INFO: readReadbackCal: data/mq946/readbackCal_C0.dat .. data/mq946/readbackCal_C15.dat
[12:39:19.235] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:39:19.243] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:39:19.243] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:39:19.243] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:39:19.243] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:39:19.259] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:39:19.259] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:39:23.633] INFO: ######################################################################
[12:39:23.633] INFO: PixTestAlive::doTest()
[12:39:23.634] INFO: ######################################################################
[12:39:23.637] INFO: ----------------------------------------------------------------------
[12:39:23.637] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:39:23.637] INFO: ----------------------------------------------------------------------
[12:39:23.639] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:39:24.019] INFO: Expecting 41600 events.
[12:39:28.427] INFO: 41600 events read in total (3690ms).
[12:39:28.574] INFO: Test took 4935ms.
[12:39:28.585] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:39:28.585] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[12:39:28.585] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[12:39:28.863] INFO: PixTestAlive::aliveTest() done
[12:39:28.863] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 2 0 0 0 0 0 0 0 0
[12:39:28.863] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 48 34 48 72 80 72 92 69 70 81 68 72 63 34 23 44
[12:39:28.890] INFO: ----------------------------------------------------------------------
[12:39:28.890] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:39:28.890] INFO: ----------------------------------------------------------------------
[12:39:28.892] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:39:29.268] INFO: Expecting 41600 events.
[12:39:32.390] INFO: 41600 events read in total (2406ms).
[12:39:32.391] INFO: Test took 3499ms.
[12:39:32.391] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:39:32.391] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[12:39:32.391] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[12:39:32.391] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:39:32.813] INFO: PixTestAlive::maskTest() done
[12:39:32.813] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:39:32.836] INFO: ----------------------------------------------------------------------
[12:39:32.836] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:39:32.836] INFO: ----------------------------------------------------------------------
[12:39:32.838] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:39:33.215] INFO: Expecting 41600 events.
[12:39:37.587] INFO: 41600 events read in total (3656ms).
[12:39:37.588] INFO: Test took 4750ms.
[12:39:37.599] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:39:37.600] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[12:39:37.600] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[12:39:37.971] INFO: PixTestAlive::addressDecodingTest() done
[12:39:37.971] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:39:37.971] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[12:39:57.846] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:39:57.846] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C4_V0 -> PixelAlive_mod
[12:39:59.614] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[12:39:59.614] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[12:39:59.614] INFO: ----------------------------------------------------------------------
[12:39:59.614] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:39:59.614] INFO: ----------------------------------------------------------------------
[12:39:59.754] INFO: Expecting 768 events.
[12:40:00.888] INFO: 768 events read in total (419ms).
[12:40:00.888] INFO: Test took 1268ms.
[12:40:00.894] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:40:01.692] INFO: Expecting 41600 events.
[12:40:05.566] INFO: 41600 events read in total (3348ms).
[12:40:05.571] INFO: Test took 4677ms.
[12:40:05.723] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:05.724] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 868810
[12:40:05.724] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[12:40:05.724] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:40:05.789] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:40:06.456] INFO: Expecting 41600 events.
[12:40:10.441] INFO: 41600 events read in total (3458ms).
[12:40:10.446] INFO: Test took 4657ms.
[12:40:10.588] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:10.588] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 875960
[12:40:10.589] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[12:40:10.589] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:40:10.657] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:40:11.324] INFO: Expecting 41600 events.
[12:40:15.350] INFO: 41600 events read in total (3500ms).
[12:40:15.355] INFO: Test took 4698ms.
[12:40:15.499] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:15.499] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 879427
[12:40:15.499] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[12:40:15.500] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:40:15.568] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:40:16.235] INFO: Expecting 41600 events.
[12:40:20.270] INFO: 41600 events read in total (3509ms).
[12:40:20.276] INFO: Test took 4708ms.
[12:40:20.417] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:20.417] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 878806
[12:40:20.417] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[12:40:20.418] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:40:20.484] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:40:21.149] INFO: Expecting 41600 events.
[12:40:25.200] INFO: 41600 events read in total (3524ms).
[12:40:25.205] INFO: Test took 4721ms.
[12:40:25.351] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:25.351] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 878882
[12:40:25.351] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[12:40:25.351] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:40:25.419] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:40:26.085] INFO: Expecting 41600 events.
[12:40:30.139] INFO: 41600 events read in total (3527ms).
[12:40:30.144] INFO: Test took 4725ms.
[12:40:30.289] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:30.289] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 880121
[12:40:30.289] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[12:40:30.290] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:40:30.358] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:40:31.024] INFO: Expecting 41600 events.
[12:40:35.077] INFO: 41600 events read in total (3526ms).
[12:40:35.083] INFO: Test took 4725ms.
[12:40:35.224] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:35.224] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 879727
[12:40:35.224] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[12:40:35.225] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:40:35.291] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:40:35.956] INFO: Expecting 41600 events.
[12:40:40.010] INFO: 41600 events read in total (3527ms).
[12:40:40.015] INFO: Test took 4724ms.
[12:40:40.159] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:40.159] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 879434
[12:40:40.159] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[12:40:40.160] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:40:40.228] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:40:40.894] INFO: Expecting 41600 events.
[12:40:44.955] INFO: 41600 events read in total (3534ms).
[12:40:44.960] INFO: Test took 4732ms.
[12:40:45.104] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:45.104] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 880207
[12:40:45.104] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[12:40:45.104] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:40:45.173] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:40:45.840] INFO: Expecting 41600 events.
[12:40:49.903] INFO: 41600 events read in total (3536ms).
[12:40:49.909] INFO: Test took 4736ms.
[12:40:50.052] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:50.053] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 881804
[12:40:50.053] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[12:40:50.053] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:40:50.121] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:40:50.787] INFO: Expecting 41600 events.
[12:40:54.857] INFO: 41600 events read in total (3544ms).
[12:40:54.862] INFO: Test took 4742ms.
[12:40:55.007] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:55.007] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 880881
[12:40:55.007] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[12:40:55.007] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:40:55.074] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:40:55.739] INFO: Expecting 41600 events.
[12:40:59.801] INFO: 41600 events read in total (3535ms).
[12:40:59.806] INFO: Test took 4732ms.
[12:40:59.953] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:59.953] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 879770
[12:40:59.953] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[12:40:59.953] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:41:00.021] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:41:00.688] INFO: Expecting 41600 events.
[12:41:04.751] INFO: 41600 events read in total (3536ms).
[12:41:04.756] INFO: Test took 4735ms.
[12:41:04.900] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:04.900] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 880224
[12:41:04.900] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[12:41:04.900] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:41:04.969] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:41:05.635] INFO: Expecting 41600 events.
[12:41:09.677] INFO: 41600 events read in total (3515ms).
[12:41:09.682] INFO: Test took 4713ms.
[12:41:09.825] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:09.825] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 878039
[12:41:09.825] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[12:41:09.826] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:41:09.894] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:41:10.560] INFO: Expecting 41600 events.
[12:41:14.628] INFO: 41600 events read in total (3541ms).
[12:41:14.634] INFO: Test took 4740ms.
[12:41:14.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:14.775] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 879409
[12:41:14.775] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[12:41:14.775] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:41:14.842] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:41:15.507] INFO: Expecting 41600 events.
[12:41:19.568] INFO: 41600 events read in total (3534ms).
[12:41:19.573] INFO: Test took 4731ms.
[12:41:19.717] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:19.717] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 878770
[12:41:19.717] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[12:41:19.718] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:41:19.786] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:41:20.453] INFO: Expecting 41600 events.
[12:41:24.518] INFO: 41600 events read in total (3539ms).
[12:41:24.523] INFO: Test took 4737ms.
[12:41:24.666] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:24.666] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 878510
[12:41:24.666] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[12:41:24.666] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:41:24.733] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:41:25.399] INFO: Expecting 41600 events.
[12:41:29.460] INFO: 41600 events read in total (3535ms).
[12:41:29.466] INFO: Test took 4733ms.
[12:41:29.610] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:29.610] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 880848
[12:41:29.610] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[12:41:29.610] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:41:29.679] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:41:30.346] INFO: Expecting 41600 events.
[12:41:34.383] INFO: 41600 events read in total (3511ms).
[12:41:34.388] INFO: Test took 4709ms.
[12:41:34.532] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:34.532] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 878679
[12:41:34.532] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[12:41:34.533] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:41:34.601] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:41:35.268] INFO: Expecting 41600 events.
[12:41:39.150] INFO: 41600 events read in total (3356ms).
[12:41:39.155] INFO: Test took 4554ms.
[12:41:39.296] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:41:39.296] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 871487
[12:41:39.296] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[12:41:39.296] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:41:39.583] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 155 eff = 0.998582
[12:41:39.584] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 151 eff = 0.999303
[12:41:39.584] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 156 eff = 0.997885
[12:41:39.584] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 169 eff = 0.996803
[12:41:39.584] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 144 eff = 0.995865
[12:41:39.585] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 153 eff = 0.995409
[12:41:39.585] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 158 eff = 0.995337
[12:41:39.585] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 147 eff = 0.995601
[12:41:39.585] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 179 eff = 0.995938
[12:41:39.586] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 155 eff = 0.995361
[12:41:39.586] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 159 eff = 0.995144
[12:41:39.586] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 165 eff = 0.995889
[12:41:39.586] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 146 eff = 0.992115
[12:41:39.587] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 196 eff = 0.997476
[12:41:39.587] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 161 eff = 0.999111
[12:41:39.587] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 137 eff = 0.998942
[12:41:44.590] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[12:41:44.590] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[12:41:44.591] INFO: ----------------------------------------------------------------------
[12:41:44.591] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:41:44.591] INFO: ----------------------------------------------------------------------
[12:41:44.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[12:41:44.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[12:41:44.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[12:41:44.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[12:41:44.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[12:41:44.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[12:41:44.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[12:41:44.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[12:41:44.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[12:41:44.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[12:41:44.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[12:41:44.591] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[12:41:44.611] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:41:45.279] INFO: Expecting 208000 events.
[12:42:02.229] INFO: 208000 events read in total (16423ms).
[12:42:02.249] INFO: Test took 17638ms.
[12:42:02.949] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:42:02.949] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4086988
[12:42:02.949] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[12:42:02.949] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:42:03.451] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 2 0 0 0 0 0 0 0 0
[12:42:03.451] INFO: number of red-efficiency pixels: 319 201 436 606 820 854 809 719 680 884 737 764 891 432 204 229
[12:42:03.451] INFO: number of X-ray hits detected: 176499 117256 199135 291261 318037 319133 313117 292705 290126 328399 320423 297521 286578 200442 120788 149012
[12:42:03.451] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:42:03.451] INFO: number of Vcal hits detected: 207671 207786 207469 207320 207060 206983 206916 207075 207212 206966 207135 207131 206742 207501 207781 207761
[12:42:03.451] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.6 99.6 99.6 99.6 99.6 99.5 99.6 99.6 99.4 99.8 99.9 99.9
[12:42:03.451] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.7 99.5 99.5 99.5 99.6 99.6 99.5 99.6 99.6 99.4 99.8 99.9 99.9
[12:42:03.451] INFO: X-ray hit rate [MHz/cm2]: 51.7 34.4 58.4 85.4 93.2 93.5 91.8 85.8 85.0 96.3 93.9 87.2 84.0 58.8 35.4 43.7
[12:42:03.452] INFO: PixTestHighRate::doXPixelAlive() done
[12:42:03.496] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[12:42:03.496] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[12:42:03.496] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[12:42:03.496] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[12:42:03.496] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[12:42:03.496] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[12:42:03.496] INFO: PixTest:: pg_setup set to default.
[12:42:15.422] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:42:15.422] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C13_V0 -> highRate_xraymap_mod
[12:42:17.294] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:42:17.295] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[12:42:17.296] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[12:42:17.296] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[12:42:17.296] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:42:17.296] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:42:17.296] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:42:17.296] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:42:17.296] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:42:17.296] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:42:17.296] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:42:17.296] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:42:17.296] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:42:17.297] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:42:17.390] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:42:17.390] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:42:17.403] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:42:17.403] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:42:17.403] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:42:17.404] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:42:17.404] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:42:17.404] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:42:17.406] QUIET: Connection to board 58 closed.
[12:42:17.486] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-904-gd94b9f2 on branch master