Test Date: 1973-11-29 16:33
Analysis date: 2016-06-14 17:32
Logfile
hrData_40.log
[14:58:08.134] INFO: *** Welcome to pxar ***
[14:58:08.134] INFO: *** Today: 2016/06/13
[14:58:08.159] INFO: *** Version: v1.9.0-814-g7497
[14:58:08.159] INFO: readRocDacs: data/mc444/dacParameters35_C0.dat .. data/mc444/dacParameters35_C15.dat
[14:58:08.160] INFO: readTbmDacs: data/mc444/tbmParameters_C0a.dat .. data/mc444/tbmParameters_C0b.dat
[14:58:08.160] INFO: readMaskFile: data/mc444/defaultMaskFile.dat
[14:58:08.160] INFO: readTrimFile: data/mc444/trimParameters35_C0.dat .. data/mc444/trimParameters35_C15.dat
[14:58:08.308] INFO: clk: 4
[14:58:08.308] INFO: ctr: 4
[14:58:08.308] INFO: sda: 19
[14:58:08.308] INFO: tin: 9
[14:58:08.308] INFO: level: 15
[14:58:08.308] INFO: triggerdelay: 0
[14:58:08.309] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[14:58:08.309] INFO: Log level: DEBUG
[14:58:08.319] QUIET: Connection to board DTB_WRMNMR opened.
[14:58:08.322] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[14:58:08.325] INFO: RPC call hashes of host and DTB match: 398089610
[14:58:09.928] INFO: DUT info:
[14:58:09.929] INFO: The DUT currently contains the following objects:
[14:58:09.929] INFO: 2 TBM Cores tbm08c (2 ON)
[14:58:09.929] INFO: TBM Core alpha (0): 7 registers set
[14:58:09.929] INFO: TBM Core beta (1): 7 registers set
[14:58:09.929] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:58:09.929] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.929] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.929] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.929] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.929] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.929] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.929] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.929] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.929] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.929] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.929] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.929] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.929] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.929] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.929] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.929] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:58:09.930] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:58:09.931] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:58:09.932] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:58:09.933] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:58:09.933] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:58:09.933] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:58:09.933] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:58:09.933] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:58:09.933] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:58:09.933] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:58:09.933] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:58:09.933] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:58:09.933] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:58:09.933] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:58:09.935] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32747520
[14:58:09.935] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xd610d0
[14:58:09.935] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xb03360
[14:58:09.935] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f6e51d94010
[14:58:09.935] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f6e57fff510
[14:58:09.935] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32755712 fPxarMemory = 0x7f6e51d94010
[14:58:09.936] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 380.2mA
[14:58:09.938] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[14:58:09.938] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.7 C
[14:58:10.431] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x114bd50
[14:58:10.495] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:58:10.496] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:58:10.496] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:58:10.501] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:58:10.501] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:58:10.501] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:58:10.512] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:58:10.512] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:58:10.532] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:58:10.532] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:58:10.548] INFO: PixTestCmd::init()
[14:58:10.552] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:58:10.552] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:58:10.553] INFO: readGainPedestalParameters data/mc444/phCalibrationFitErr35_C0.dat .. data/mc444/phCalibrationFitErr35_C15.dat
[14:58:10.796] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:58:10.796] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:58:10.810] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:58:10.815] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:58:10.816] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:58:10.816] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:58:10.838] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:58:10.838] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:58:10.838] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:58:10.838] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:58:10.838] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:58:10.844] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:58:10.844] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:58:10.860] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:58:10.860] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:58:10.860] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:58:10.860] INFO: readReadbackCal: data/mc444/readbackCal_C0.dat .. data/mc444/readbackCal_C15.dat
[14:58:10.869] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:58:10.877] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[14:58:10.877] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[14:58:10.877] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[14:58:10.877] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[14:58:10.889] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:58:10.889] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:58:15.420] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[14:58:18.044] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[14:58:19.545] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[14:58:19.545] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[14:58:19.545] INFO: ----------------------------------------------------------------------
[14:58:19.545] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[14:58:19.545] INFO: ----------------------------------------------------------------------
[14:58:20.519] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[14:58:31.471] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[14:58:31.475] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:58:58.093] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1079156 events.
[14:59:05.965] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1079156, pixels seen in all events: 7302424
[14:59:06.242] INFO: Resuming triggers.
[14:59:17.201] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[14:59:17.235] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:59:43.855] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1079659 events.
[14:59:51.863] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1079659, pixels seen in all events: 7295034
[14:59:52.048] INFO: Resuming triggers.
[15:00:03.011] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[15:00:03.048] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:00:29.649] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1079979 events.
[15:00:37.598] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1079979, pixels seen in all events: 7291556
[15:00:37.781] INFO: Resuming triggers.
[15:00:48.743] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[15:00:48.774] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:01:15.279] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1079966 events.
[15:01:23.234] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1079966, pixels seen in all events: 7292143
[15:01:23.418] INFO: Resuming triggers.
[15:01:34.370] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[15:01:34.407] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:02:00.956] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1079036 events.
[15:02:08.923] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1079036, pixels seen in all events: 7303280
[15:02:09.106] INFO: Resuming triggers.
[15:02:14.389] INFO: data taking finished, elapsed time: 60 seconds.
[15:02:14.585] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:02:27.446] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 520432 events.
[15:02:31.231] DEBUG: <PixTestXray.cc/processData:L823> # events read: 520432, pixels seen in all events: 3521569
[15:02:31.338] INFO: PixTest:: pg_setup set to default.
[15:02:31.379] INFO: PixTestXray::doPhRun() done
[15:02:43.137] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:02:43.138] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 383.5mA
[15:02:43.138] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[15:02:43.138] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:02:43.138] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:02:43.138] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:02:43.139] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:02:43.139] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:02:43.139] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:02:43.139] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:02:43.139] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:02:43.139] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:02:43.139] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:02:43.139] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:02:43.139] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:02:43.139] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:02:43.139] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:02:43.139] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:02:43.139] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:02:43.139] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:02:43.139] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:02:43.139] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:02:43.421] QUIET: Connection to board 43 closed.
[15:02:43.501] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[15:02:49.928] INFO: *** Welcome to pxar ***
[15:02:49.928] INFO: *** Today: 2016/06/13
[15:02:49.954] INFO: *** Version: v1.9.0-814-g7497
[15:02:49.954] INFO: readRocDacs: data/mc444/dacParameters35_C0.dat .. data/mc444/dacParameters35_C15.dat
[15:02:49.955] INFO: readTbmDacs: data/mc444/tbmParameters_C0a.dat .. data/mc444/tbmParameters_C0b.dat
[15:02:49.955] INFO: readMaskFile: data/mc444/defaultMaskFile.dat
[15:02:49.955] INFO: readTrimFile: data/mc444/trimParameters35_C0.dat .. data/mc444/trimParameters35_C15.dat
[15:02:50.104] INFO: clk: 4
[15:02:50.104] INFO: ctr: 4
[15:02:50.104] INFO: sda: 19
[15:02:50.104] INFO: tin: 9
[15:02:50.104] INFO: level: 15
[15:02:50.104] INFO: triggerdelay: 0
[15:02:50.104] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:02:50.104] INFO: Log level: DEBUG
[15:02:50.115] QUIET: Connection to board DTB_WRMNMR opened.
[15:02:50.119] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[15:02:50.121] INFO: RPC call hashes of host and DTB match: 398089610
[15:02:51.723] INFO: DUT info:
[15:02:51.723] INFO: The DUT currently contains the following objects:
[15:02:51.723] INFO: 2 TBM Cores tbm08c (2 ON)
[15:02:51.723] INFO: TBM Core alpha (0): 7 registers set
[15:02:51.723] INFO: TBM Core beta (1): 7 registers set
[15:02:51.723] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:02:51.723] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:51.724] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:02:51.724] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:02:51.724] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:02:51.724] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:02:51.724] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:02:51.724] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:02:51.724] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:51.724] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:02:51.724] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:02:51.724] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:02:51.724] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:02:51.725] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:02:51.726] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:02:51.727] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:02:51.730] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33476608
[15:02:51.730] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x246c0d0
[15:02:51.730] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x220e360
[15:02:51.730] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f9031d94010
[15:02:51.730] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f9037fff510
[15:02:51.730] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33484800 fPxarMemory = 0x7f9031d94010
[15:02:51.731] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 380.2mA
[15:02:51.732] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[15:02:51.732] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:02:52.229] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2856d50
[15:02:52.291] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:02:52.291] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:02:52.291] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:02:52.298] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:02:52.298] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:02:52.298] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:02:52.310] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:02:52.310] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:02:52.318] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:02:52.318] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:02:52.343] INFO: PixTestCmd::init()
[15:02:52.350] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:02:52.350] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:02:52.350] INFO: readGainPedestalParameters data/mc444/phCalibrationFitErr35_C0.dat .. data/mc444/phCalibrationFitErr35_C15.dat
[15:02:52.594] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:02:52.594] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:02:52.613] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:02:52.620] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:02:52.620] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:02:52.620] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:02:52.643] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:02:52.643] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:02:52.643] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:02:52.644] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:02:52.644] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:02:52.650] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:02:52.650] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:02:52.668] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:02:52.668] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:02:52.668] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:02:52.668] INFO: readReadbackCal: data/mc444/readbackCal_C0.dat .. data/mc444/readbackCal_C15.dat
[15:02:52.676] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:02:52.684] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:02:52.684] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:02:52.684] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:02:52.684] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:02:52.700] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:02:52.700] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:03:00.348] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[15:03:02.748] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[15:03:04.857] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[15:03:04.857] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[15:03:04.857] INFO: ----------------------------------------------------------------------
[15:03:04.857] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[15:03:04.857] INFO: ----------------------------------------------------------------------
[15:03:05.831] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[15:03:12.839] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:03:12.842] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:03:37.822] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 690468 events.
[15:03:50.558] DEBUG: <PixTestXray.cc/processData:L823> # events read: 690468, pixels seen in all events: 11967550
[15:03:50.762] INFO: Resuming triggers.
[15:03:57.771] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[15:03:57.805] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:04:22.607] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 690453 events.
[15:04:35.293] DEBUG: <PixTestXray.cc/processData:L823> # events read: 690453, pixels seen in all events: 11967281
[15:04:35.439] INFO: Resuming triggers.
[15:04:42.448] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[15:04:42.486] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:05:07.291] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 690479 events.
[15:05:19.991] DEBUG: <PixTestXray.cc/processData:L823> # events read: 690479, pixels seen in all events: 11966972
[15:05:20.179] INFO: Resuming triggers.
[15:05:27.190] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[15:05:27.226] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:05:52.042] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 690682 events.
[15:06:04.801] DEBUG: <PixTestXray.cc/processData:L823> # events read: 690682, pixels seen in all events: 11963479
[15:06:04.977] INFO: Resuming triggers.
[15:06:11.991] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[15:06:12.049] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:06:36.782] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 690897 events.
[15:06:49.535] DEBUG: <PixTestXray.cc/processData:L823> # events read: 690897, pixels seen in all events: 11961331
[15:06:49.719] INFO: Resuming triggers.
[15:06:56.731] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[15:06:56.774] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:07:21.444] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 690801 events.
[15:07:34.213] DEBUG: <PixTestXray.cc/processData:L823> # events read: 690801, pixels seen in all events: 11962585
[15:07:34.401] INFO: Resuming triggers.
[15:07:41.416] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[15:07:41.454] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:08:06.231] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 690995 events.
[15:08:18.983] DEBUG: <PixTestXray.cc/processData:L823> # events read: 690995, pixels seen in all events: 11960638
[15:08:19.157] INFO: Resuming triggers.
[15:08:26.168] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[15:08:26.220] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:08:51.000] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 690662 events.
[15:09:03.730] DEBUG: <PixTestXray.cc/processData:L823> # events read: 690662, pixels seen in all events: 11964073
[15:09:03.922] INFO: Resuming triggers.
[15:09:07.909] INFO: data taking finished, elapsed time: 60 seconds.
[15:09:08.105] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:09:22.234] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 392773 events.
[15:09:29.448] DEBUG: <PixTestXray.cc/processData:L823> # events read: 392773, pixels seen in all events: 6800138
[15:09:29.542] INFO: PixTest:: pg_setup set to default.
[15:09:29.583] INFO: PixTestXray::doPhRun() done
[15:11:03.393] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:11:03.394] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 383.5mA
[15:11:03.394] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[15:11:03.394] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[15:11:03.395] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:11:03.395] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:11:03.395] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:11:03.395] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:11:03.395] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:11:03.395] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:11:03.395] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:11:03.395] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:11:03.395] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:11:03.395] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:11:03.395] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:11:03.395] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:11:03.395] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:11:03.395] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:11:03.396] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:11:03.396] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:11:03.396] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:11:03.396] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:11:03.714] QUIET: Connection to board 43 closed.
[15:11:03.794] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[14:49:17.758] INFO: *** Welcome to pxar ***
[14:49:17.758] INFO: *** Today: 2016/06/13
[14:49:17.785] INFO: *** Version: v1.9.0-814-g7497
[14:49:17.786] INFO: readRocDacs: data/mc444/dacParameters35_C0.dat .. data/mc444/dacParameters35_C15.dat
[14:49:17.787] INFO: readTbmDacs: data/mc444/tbmParameters_C0a.dat .. data/mc444/tbmParameters_C0b.dat
[14:49:17.787] INFO: readMaskFile: data/mc444/defaultMaskFile.dat
[14:49:17.787] INFO: readTrimFile: data/mc444/trimParameters35_C0.dat .. data/mc444/trimParameters35_C15.dat
[14:49:17.938] INFO: clk: 4
[14:49:17.938] INFO: ctr: 4
[14:49:17.938] INFO: sda: 19
[14:49:17.938] INFO: tin: 9
[14:49:17.938] INFO: level: 15
[14:49:17.938] INFO: triggerdelay: 0
[14:49:17.938] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[14:49:17.938] INFO: Log level: DEBUG
[14:49:17.949] QUIET: Connection to board DTB_WRMNMR opened.
[14:49:17.952] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[14:49:17.954] INFO: RPC call hashes of host and DTB match: 398089610
[14:49:19.558] INFO: DUT info:
[14:49:19.558] INFO: The DUT currently contains the following objects:
[14:49:19.558] INFO: 2 TBM Cores tbm08c (2 ON)
[14:49:19.558] INFO: TBM Core alpha (0): 7 registers set
[14:49:19.558] INFO: TBM Core beta (1): 7 registers set
[14:49:19.558] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:49:19.558] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.558] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.558] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.558] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.558] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.558] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.558] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.558] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.559] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.559] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.559] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.559] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.559] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.559] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.559] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.559] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:49:19.559] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:49:19.560] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:49:19.561] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:49:19.562] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:49:19.564] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32956416
[14:49:19.564] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x20a20d0
[14:49:19.564] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1e44360
[14:49:19.564] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fc7b5d94010
[14:49:19.564] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fc7bbfff510
[14:49:19.564] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32964608 fPxarMemory = 0x7fc7b5d94010
[14:49:19.566] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 380.2mA
[14:49:19.567] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[14:49:19.567] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[14:49:20.060] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x248ccf0
[14:49:20.121] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:49:20.122] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:49:20.122] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:49:20.128] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:49:20.128] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:49:20.128] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:49:20.143] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:49:20.143] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:49:20.164] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:49:20.164] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:49:20.179] INFO: PixTestCmd::init()
[14:49:20.190] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:49:20.190] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:49:20.190] INFO: readGainPedestalParameters data/mc444/phCalibrationFitErr35_C0.dat .. data/mc444/phCalibrationFitErr35_C15.dat
[14:49:20.431] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:49:20.432] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:49:20.446] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:49:20.453] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:49:20.453] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:49:20.453] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:49:20.475] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:49:20.475] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:49:20.475] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:49:20.475] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:49:20.475] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:49:20.483] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:49:20.483] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:49:20.504] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:49:20.504] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:49:20.504] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:49:20.504] INFO: readReadbackCal: data/mc444/readbackCal_C0.dat .. data/mc444/readbackCal_C15.dat
[14:49:20.514] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:49:20.522] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[14:49:20.522] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[14:49:20.522] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[14:49:20.522] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[14:49:20.540] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:49:20.540] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:49:27.228] INFO: ######################################################################
[14:49:27.228] INFO: PixTestAlive::doTest()
[14:49:27.228] INFO: ######################################################################
[14:49:27.231] INFO: ----------------------------------------------------------------------
[14:49:27.231] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:49:27.231] INFO: ----------------------------------------------------------------------
[14:49:27.234] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:27.612] INFO: Expecting 41600 events.
[14:49:31.854] INFO: 41600 events read in total (3523ms).
[14:49:32.001] INFO: Test took 4767ms.
[14:49:32.012] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:32.012] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:49:32.012] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:49:32.284] INFO: PixTestAlive::aliveTest() done
[14:49:32.284] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:49:32.284] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 16 22 28 44 47 51 37 36 47 51 49 34 42 36 25 20
[14:49:32.309] INFO: ----------------------------------------------------------------------
[14:49:32.309] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:49:32.309] INFO: ----------------------------------------------------------------------
[14:49:32.311] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:32.689] INFO: Expecting 41600 events.
[14:49:35.631] INFO: 41600 events read in total (2227ms).
[14:49:35.632] INFO: Test took 3321ms.
[14:49:35.632] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:35.632] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[14:49:35.632] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[14:49:35.632] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:49:36.054] INFO: PixTestAlive::maskTest() done
[14:49:36.054] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:49:36.073] INFO: ----------------------------------------------------------------------
[14:49:36.074] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:49:36.074] INFO: ----------------------------------------------------------------------
[14:49:36.076] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:36.452] INFO: Expecting 41600 events.
[14:49:40.678] INFO: 41600 events read in total (3510ms).
[14:49:40.680] INFO: Test took 4604ms.
[14:49:40.691] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:40.691] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:49:40.691] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[14:49:41.060] INFO: PixTestAlive::addressDecodingTest() done
[14:49:41.060] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:49:41.060] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[14:51:18.696] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[14:51:18.696] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:51:18.696] INFO: ----------------------------------------------------------------------
[14:51:18.696] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:51:18.696] INFO: ----------------------------------------------------------------------
[14:51:18.835] INFO: Expecting 768 events.
[14:51:19.963] INFO: 768 events read in total (413ms).
[14:51:19.964] INFO: Test took 1262ms.
[14:51:19.970] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:20.767] INFO: Expecting 41600 events.
[14:51:23.959] INFO: 41600 events read in total (2665ms).
[14:51:23.963] INFO: Test took 3993ms.
[14:51:24.053] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:24.053] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 518921
[14:51:24.053] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:51:24.053] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:24.094] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:24.760] INFO: Expecting 41600 events.
[14:51:28.103] INFO: 41600 events read in total (2817ms).
[14:51:28.107] INFO: Test took 4012ms.
[14:51:28.200] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:28.201] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 528919
[14:51:28.201] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:51:28.201] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:28.243] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:28.908] INFO: Expecting 41600 events.
[14:51:32.292] INFO: 41600 events read in total (2857ms).
[14:51:32.298] INFO: Test took 4055ms.
[14:51:32.391] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:32.391] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 530223
[14:51:32.391] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:51:32.391] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:32.435] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:33.100] INFO: Expecting 41600 events.
[14:51:36.496] INFO: 41600 events read in total (2869ms).
[14:51:36.502] INFO: Test took 4067ms.
[14:51:36.597] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:36.597] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 531493
[14:51:36.597] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:51:36.597] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:36.640] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:37.305] INFO: Expecting 41600 events.
[14:51:40.705] INFO: 41600 events read in total (2873ms).
[14:51:40.711] INFO: Test took 4071ms.
[14:51:40.804] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:40.804] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 531447
[14:51:40.804] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:51:40.805] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:40.847] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:41.512] INFO: Expecting 41600 events.
[14:51:44.923] INFO: 41600 events read in total (2884ms).
[14:51:44.927] INFO: Test took 4080ms.
[14:51:45.019] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:45.019] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 530943
[14:51:45.019] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:51:45.019] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:45.063] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:45.728] INFO: Expecting 41600 events.
[14:51:49.143] INFO: 41600 events read in total (2888ms).
[14:51:49.147] INFO: Test took 4084ms.
[14:51:49.238] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:49.238] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 531693
[14:51:49.238] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:51:49.239] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:49.281] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:49.947] INFO: Expecting 41600 events.
[14:51:53.374] INFO: 41600 events read in total (2901ms).
[14:51:53.378] INFO: Test took 4096ms.
[14:51:53.471] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:53.471] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 533988
[14:51:53.471] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:51:53.471] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:53.515] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:54.180] INFO: Expecting 41600 events.
[14:51:57.591] INFO: 41600 events read in total (2885ms).
[14:51:57.595] INFO: Test took 4080ms.
[14:51:57.686] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:57.686] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 533115
[14:51:57.686] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:51:57.687] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:57.730] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:58.395] INFO: Expecting 41600 events.
[14:52:01.796] INFO: 41600 events read in total (2874ms).
[14:52:01.800] INFO: Test took 4070ms.
[14:52:01.893] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:01.893] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 532464
[14:52:01.893] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:52:01.893] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:01.936] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:52:02.601] INFO: Expecting 41600 events.
[14:52:06.035] INFO: 41600 events read in total (2907ms).
[14:52:06.039] INFO: Test took 4103ms.
[14:52:06.131] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:06.131] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 531570
[14:52:06.131] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:52:06.132] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:06.175] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:52:06.840] INFO: Expecting 41600 events.
[14:52:10.255] INFO: 41600 events read in total (2889ms).
[14:52:10.259] INFO: Test took 4084ms.
[14:52:10.350] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:10.350] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 532180
[14:52:10.350] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:52:10.350] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:10.394] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:52:11.058] INFO: Expecting 41600 events.
[14:52:14.478] INFO: 41600 events read in total (2893ms).
[14:52:14.482] INFO: Test took 4088ms.
[14:52:14.574] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:14.574] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 534015
[14:52:14.574] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:52:14.575] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:14.618] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:52:15.284] INFO: Expecting 41600 events.
[14:52:18.718] INFO: 41600 events read in total (2908ms).
[14:52:18.722] INFO: Test took 4104ms.
[14:52:18.815] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:18.815] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 532682
[14:52:18.815] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:52:18.815] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:18.860] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:52:19.526] INFO: Expecting 41600 events.
[14:52:22.948] INFO: 41600 events read in total (2895ms).
[14:52:22.952] INFO: Test took 4092ms.
[14:52:23.043] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:23.044] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 531419
[14:52:23.044] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:52:23.044] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:23.089] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:52:23.755] INFO: Expecting 41600 events.
[14:52:27.173] INFO: 41600 events read in total (2892ms).
[14:52:27.177] INFO: Test took 4088ms.
[14:52:27.270] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:27.271] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 533060
[14:52:27.271] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:52:27.271] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:27.315] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:52:27.982] INFO: Expecting 41600 events.
[14:52:31.395] INFO: 41600 events read in total (2887ms).
[14:52:31.398] INFO: Test took 4083ms.
[14:52:31.491] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:31.491] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 531362
[14:52:31.491] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:52:31.492] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:31.536] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:52:32.202] INFO: Expecting 41600 events.
[14:52:35.622] INFO: 41600 events read in total (2893ms).
[14:52:35.626] INFO: Test took 4090ms.
[14:52:35.723] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:35.723] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 532645
[14:52:35.723] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:52:35.724] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:35.767] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:52:36.433] INFO: Expecting 41600 events.
[14:52:39.831] INFO: 41600 events read in total (2871ms).
[14:52:39.837] INFO: Test took 4070ms.
[14:52:39.931] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:39.931] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 530879
[14:52:39.931] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:52:39.931] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:39.974] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:52:40.639] INFO: Expecting 41600 events.
[14:52:43.917] INFO: 41600 events read in total (2751ms).
[14:52:43.921] INFO: Test took 3947ms.
[14:52:44.009] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:44.009] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 526846
[14:52:44.009] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:52:44.010] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:52:44.275] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 175 eff = 0.999639
[14:52:44.276] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 147 eff = 0.999712
[14:52:44.276] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 176 eff = 0.999135
[14:52:44.276] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 148 eff = 0.998654
[14:52:44.276] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 157 eff = 0.998678
[14:52:44.277] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 160 eff = 0.978438
[14:52:44.277] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 188 eff = 0.998822
[14:52:44.277] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 152 eff = 0.99899
[14:52:44.277] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 168 eff = 0.998774
[14:52:44.278] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 193 eff = 0.99875
[14:52:44.278] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 152 eff = 0.998726
[14:52:44.278] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 152 eff = 0.998774
[14:52:44.278] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 180 eff = 0.998798
[14:52:44.279] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 148 eff = 0.999207
[14:52:44.279] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 158 eff = 0.999615
[14:52:44.279] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 153 eff = 0.999543
[14:53:38.905] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[14:53:38.905] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:53:38.905] INFO: ----------------------------------------------------------------------
[14:53:38.905] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:53:38.905] INFO: ----------------------------------------------------------------------
[14:53:38.905] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:53:38.905] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:53:38.905] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:53:38.905] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:53:38.905] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:53:38.905] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:53:38.905] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:53:38.905] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:53:38.905] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:53:38.905] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:53:38.905] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:53:38.905] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:53:38.924] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:39.592] INFO: Expecting 208000 events.
[14:53:53.323] INFO: 208000 events read in total (13204ms).
[14:53:53.337] INFO: Test took 14413ms.
[14:53:53.762] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:53.762] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2382637
[14:53:53.762] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:53:53.763] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:54.153] INFO: number of dead pixels (per ROC): 0 0 0 0 0 160 0 0 0 0 0 0 0 0 0 0
[14:53:54.153] INFO: number of red-efficiency pixels: 142 96 181 246 325 487 290 284 267 312 379 235 258 207 98 103
[14:53:54.153] INFO: number of X-ray hits detected: 93995 69148 115697 171110 179166 172909 180193 170104 183527 191302 184001 169471 173057 113686 68955 79916
[14:53:54.153] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:53:54.153] INFO: number of Vcal hits detected: 207854 207900 207804 207740 207655 199661 207691 207705 207722 207676 207607 207757 207729 207790 207900 207893
[14:53:54.153] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.8 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 100.0 99.9
[14:53:54.153] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.8 96.0 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 99.9
[14:53:54.153] INFO: X-ray hit rate [MHz/cm2]: 27.6 20.3 33.9 50.2 52.5 50.7 52.8 49.9 53.8 56.1 53.9 49.7 50.7 33.3 20.2 23.4
[14:53:54.153] INFO: PixTestHighRate::doXPixelAlive() done
[14:53:54.199] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:53:54.199] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:53:54.199] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:53:54.199] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:53:54.199] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:53:54.199] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:53:54.199] INFO: PixTest:: pg_setup set to default.
[14:54:08.769] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:54:08.769] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[14:54:10.713] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[14:54:10.713] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 383.5mA
[14:54:10.714] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[14:54:10.714] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[14:54:10.714] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[14:54:10.714] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[14:54:10.714] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[14:54:10.714] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[14:54:10.715] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[14:54:10.715] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[14:54:10.715] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[14:54:10.715] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[14:54:10.715] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[14:54:10.715] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:54:10.804] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[14:54:10.804] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:54:10.813] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[14:54:10.813] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[14:54:10.813] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[14:54:10.813] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[14:54:10.813] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[14:54:10.813] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:54:10.816] QUIET: Connection to board 43 closed.
[14:54:10.895] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[14:39:51.021] INFO: *** Welcome to pxar ***
[14:39:51.021] INFO: *** Today: 2016/06/13
[14:39:51.045] INFO: *** Version: v1.9.0-814-g7497
[14:39:51.045] INFO: readRocDacs: data/mc444/dacParameters35_C0.dat .. data/mc444/dacParameters35_C15.dat
[14:39:51.046] INFO: readTbmDacs: data/mc444/tbmParameters_C0a.dat .. data/mc444/tbmParameters_C0b.dat
[14:39:51.046] INFO: readMaskFile: data/mc444/defaultMaskFile.dat
[14:39:51.046] INFO: readTrimFile: data/mc444/trimParameters35_C0.dat .. data/mc444/trimParameters35_C15.dat
[14:39:51.195] INFO: clk: 4
[14:39:51.195] INFO: ctr: 4
[14:39:51.195] INFO: sda: 19
[14:39:51.195] INFO: tin: 9
[14:39:51.195] INFO: level: 15
[14:39:51.195] INFO: triggerdelay: 0
[14:39:51.195] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[14:39:51.195] INFO: Log level: DEBUG
[14:39:51.208] QUIET: Connection to board DTB_WRMNMR opened.
[14:39:51.211] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[14:39:51.214] INFO: RPC call hashes of host and DTB match: 398089610
[14:39:52.818] INFO: DUT info:
[14:39:52.818] INFO: The DUT currently contains the following objects:
[14:39:52.818] INFO: 2 TBM Cores tbm08c (2 ON)
[14:39:52.818] INFO: TBM Core alpha (0): 7 registers set
[14:39:52.818] INFO: TBM Core beta (1): 7 registers set
[14:39:52.818] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:39:52.818] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.818] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.818] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.818] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.818] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.818] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.818] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.818] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.818] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.818] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.818] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.818] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.818] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.818] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.818] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.818] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:39:52.819] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:39:52.820] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:39:52.821] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:39:52.822] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:39:52.822] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:39:52.822] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:39:52.822] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:39:52.822] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:39:52.822] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:39:52.822] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:39:52.822] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:39:52.822] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:39:52.822] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:39:52.822] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:39:52.822] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:39:52.822] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:39:52.824] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35696640
[14:39:52.824] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x27180d0
[14:39:52.824] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x24ba360
[14:39:52.824] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f93b9393010
[14:39:52.824] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f93bf5fe510
[14:39:52.824] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35704832 fPxarMemory = 0x7f93b9393010
[14:39:52.825] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 380.2mA
[14:39:52.827] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[14:39:52.827] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[14:39:53.322] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2b02d50
[14:39:53.383] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:39:53.383] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:39:53.384] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:39:53.389] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:39:53.389] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:39:53.389] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:39:53.397] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:39:53.397] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:39:53.415] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:39:53.415] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:39:53.441] INFO: PixTestCmd::init()
[14:39:53.453] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:39:53.453] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:39:53.453] INFO: readGainPedestalParameters data/mc444/phCalibrationFitErr35_C0.dat .. data/mc444/phCalibrationFitErr35_C15.dat
[14:39:53.695] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:39:53.695] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:39:53.708] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:39:53.713] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:39:53.713] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:39:53.713] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:39:53.740] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:39:53.740] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:39:53.740] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:39:53.740] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:39:53.740] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:39:53.746] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:39:53.746] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:39:53.759] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:39:53.759] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:39:53.759] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:39:53.759] INFO: readReadbackCal: data/mc444/readbackCal_C0.dat .. data/mc444/readbackCal_C15.dat
[14:39:53.775] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:39:53.782] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[14:39:53.782] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[14:39:53.782] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[14:39:53.782] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[14:39:53.804] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:39:53.804] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:40:00.252] INFO: ######################################################################
[14:40:00.252] INFO: PixTestAlive::doTest()
[14:40:00.252] INFO: ######################################################################
[14:40:00.255] INFO: ----------------------------------------------------------------------
[14:40:00.255] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:40:00.255] INFO: ----------------------------------------------------------------------
[14:40:00.258] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:40:00.638] INFO: Expecting 41600 events.
[14:40:04.872] INFO: 41600 events read in total (3516ms).
[14:40:05.018] INFO: Test took 4760ms.
[14:40:05.028] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:05.028] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:40:05.028] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:40:05.310] INFO: PixTestAlive::aliveTest() done
[14:40:05.310] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:40:05.310] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 46 31 54 74 68 74 83 51 80 76 65 57 72 58 38 27
[14:40:05.339] INFO: ----------------------------------------------------------------------
[14:40:05.339] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:40:05.339] INFO: ----------------------------------------------------------------------
[14:40:05.341] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:40:05.716] INFO: Expecting 41600 events.
[14:40:08.659] INFO: 41600 events read in total (2227ms).
[14:40:08.660] INFO: Test took 3319ms.
[14:40:08.660] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:08.660] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[14:40:08.660] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[14:40:08.661] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:40:09.081] INFO: PixTestAlive::maskTest() done
[14:40:09.081] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:40:09.104] INFO: ----------------------------------------------------------------------
[14:40:09.104] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:40:09.104] INFO: ----------------------------------------------------------------------
[14:40:09.107] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:40:09.486] INFO: Expecting 41600 events.
[14:40:13.684] INFO: 41600 events read in total (3483ms).
[14:40:13.686] INFO: Test took 4579ms.
[14:40:13.697] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:13.697] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:40:13.697] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[14:40:14.065] INFO: PixTestAlive::addressDecodingTest() done
[14:40:14.065] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:40:14.065] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[14:40:16.992] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[14:40:16.992] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:40:16.992] INFO: ----------------------------------------------------------------------
[14:40:16.993] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:40:16.993] INFO: ----------------------------------------------------------------------
[14:40:17.132] INFO: Expecting 768 events.
[14:40:18.261] INFO: 768 events read in total (414ms).
[14:40:18.261] INFO: Test took 1263ms.
[14:40:18.267] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:40:19.064] INFO: Expecting 41600 events.
[14:40:22.548] INFO: 41600 events read in total (2957ms).
[14:40:22.553] INFO: Test took 4286ms.
[14:40:22.695] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:22.695] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 816915
[14:40:22.695] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:40:22.695] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:40:22.755] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:40:23.420] INFO: Expecting 41600 events.
[14:40:27.102] INFO: 41600 events read in total (3156ms).
[14:40:27.108] INFO: Test took 4353ms.
[14:40:27.244] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:27.244] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 829357
[14:40:27.244] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:40:27.245] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:40:27.307] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:40:27.972] INFO: Expecting 41600 events.
[14:40:31.660] INFO: 41600 events read in total (3161ms).
[14:40:31.666] INFO: Test took 4359ms.
[14:40:31.803] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:31.803] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 833095
[14:40:31.803] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:40:31.803] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:40:31.867] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:40:32.533] INFO: Expecting 41600 events.
[14:40:36.288] INFO: 41600 events read in total (3228ms).
[14:40:36.293] INFO: Test took 4426ms.
[14:40:36.431] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:36.431] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 834249
[14:40:36.431] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:40:36.432] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:40:36.497] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:40:37.164] INFO: Expecting 41600 events.
[14:40:40.926] INFO: 41600 events read in total (3235ms).
[14:40:40.931] INFO: Test took 4434ms.
[14:40:41.067] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:41.067] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 834508
[14:40:41.067] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:40:41.068] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:40:41.131] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:40:41.796] INFO: Expecting 41600 events.
[14:40:45.580] INFO: 41600 events read in total (3258ms).
[14:40:45.586] INFO: Test took 4455ms.
[14:40:45.724] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:45.724] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 833713
[14:40:45.724] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:40:45.724] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:40:45.788] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:40:46.452] INFO: Expecting 41600 events.
[14:40:50.229] INFO: 41600 events read in total (3250ms).
[14:40:50.234] INFO: Test took 4446ms.
[14:40:50.371] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:50.371] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 832938
[14:40:50.371] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:40:50.371] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:40:50.435] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:40:51.101] INFO: Expecting 41600 events.
[14:40:54.866] INFO: 41600 events read in total (3238ms).
[14:40:54.871] INFO: Test took 4436ms.
[14:40:55.009] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:55.009] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 834456
[14:40:55.009] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:40:55.009] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:40:55.073] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:40:55.738] INFO: Expecting 41600 events.
[14:40:59.523] INFO: 41600 events read in total (3259ms).
[14:40:59.529] INFO: Test took 4456ms.
[14:40:59.667] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:59.667] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 833927
[14:40:59.667] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:40:59.667] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:40:59.732] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:41:00.398] INFO: Expecting 41600 events.
[14:41:04.178] INFO: 41600 events read in total (3253ms).
[14:41:04.184] INFO: Test took 4452ms.
[14:41:04.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:04.319] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 833489
[14:41:04.319] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:41:04.319] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:41:04.382] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:41:05.049] INFO: Expecting 41600 events.
[14:41:08.827] INFO: 41600 events read in total (3252ms).
[14:41:08.832] INFO: Test took 4449ms.
[14:41:08.968] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:08.968] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 833545
[14:41:08.968] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:41:08.969] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:41:09.034] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:41:09.700] INFO: Expecting 41600 events.
[14:41:13.472] INFO: 41600 events read in total (3246ms).
[14:41:13.478] INFO: Test took 4444ms.
[14:41:13.613] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:13.613] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 833178
[14:41:13.613] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:41:13.614] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:41:13.679] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:41:14.345] INFO: Expecting 41600 events.
[14:41:18.119] INFO: 41600 events read in total (3247ms).
[14:41:18.124] INFO: Test took 4445ms.
[14:41:18.260] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:18.260] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 833991
[14:41:18.260] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:41:18.261] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:41:18.324] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:41:18.989] INFO: Expecting 41600 events.
[14:41:22.760] INFO: 41600 events read in total (3245ms).
[14:41:22.765] INFO: Test took 4441ms.
[14:41:22.902] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:22.902] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 833868
[14:41:22.902] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:41:22.903] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:41:22.967] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:41:23.633] INFO: Expecting 41600 events.
[14:41:27.408] INFO: 41600 events read in total (3248ms).
[14:41:27.413] INFO: Test took 4446ms.
[14:41:27.550] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:27.550] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 834428
[14:41:27.550] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:41:27.550] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:41:27.614] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:41:28.280] INFO: Expecting 41600 events.
[14:41:32.054] INFO: 41600 events read in total (3247ms).
[14:41:32.059] INFO: Test took 4445ms.
[14:41:32.196] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:32.196] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 834419
[14:41:32.196] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:41:32.197] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:41:32.260] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:41:32.925] INFO: Expecting 41600 events.
[14:41:36.696] INFO: 41600 events read in total (3244ms).
[14:41:36.701] INFO: Test took 4441ms.
[14:41:36.838] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:36.838] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 832708
[14:41:36.838] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:41:36.838] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:41:36.903] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:41:37.569] INFO: Expecting 41600 events.
[14:41:41.337] INFO: 41600 events read in total (3242ms).
[14:41:41.342] INFO: Test took 4439ms.
[14:41:41.480] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:41.480] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 832218
[14:41:41.481] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:41:41.481] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:41:41.544] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:41:42.209] INFO: Expecting 41600 events.
[14:41:45.960] INFO: 41600 events read in total (3224ms).
[14:41:45.965] INFO: Test took 4421ms.
[14:41:46.102] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:46.102] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 832376
[14:41:46.102] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:41:46.102] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:41:46.165] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:41:46.830] INFO: Expecting 41600 events.
[14:41:50.440] INFO: 41600 events read in total (3083ms).
[14:41:50.445] INFO: Test took 4279ms.
[14:41:50.580] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:50.580] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 828115
[14:41:50.580] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:41:50.580] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:41:50.864] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 181 eff = 0.999014
[14:41:50.864] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 163 eff = 0.999135
[14:41:50.864] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 183 eff = 0.998029
[14:41:50.865] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 145 eff = 0.997115
[14:41:50.865] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 160 eff = 0.996466
[14:41:50.865] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 169 eff = 0.959063
[14:41:50.865] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 192 eff = 0.99625
[14:41:50.865] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 154 eff = 0.996514
[14:41:50.865] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 168 eff = 0.996587
[14:41:50.865] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 192 eff = 0.995865
[14:41:50.866] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 155 eff = 0.995697
[14:41:50.866] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 150 eff = 0.997548
[14:41:50.866] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 183 eff = 0.996803
[14:41:50.866] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 156 eff = 0.998005
[14:41:50.866] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 154 eff = 0.999279
[14:41:50.866] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 162 eff = 0.999327
[14:48:47.713] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[14:48:47.713] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:48:47.713] INFO: ----------------------------------------------------------------------
[14:48:47.713] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:48:47.713] INFO: ----------------------------------------------------------------------
[14:48:47.713] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:48:47.713] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:48:47.713] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:48:47.713] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:48:47.713] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:48:47.713] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:48:47.713] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:48:47.713] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:48:47.713] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:48:47.713] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:48:47.713] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:48:47.713] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:48:47.732] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:48.401] INFO: Expecting 208000 events.
[14:49:04.733] INFO: 208000 events read in total (15806ms).
[14:49:04.753] INFO: Test took 17021ms.
[14:49:05.484] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:05.484] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3853253
[14:49:05.484] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:49:05.484] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:49:05.974] INFO: number of dead pixels (per ROC): 0 0 0 0 0 160 0 0 0 0 0 0 0 0 0 0
[14:49:05.974] INFO: number of red-efficiency pixels: 240 191 389 668 670 880 652 626 638 729 764 600 571 360 213 180
[14:49:05.974] INFO: number of X-ray hits detected: 153275 113297 188960 279069 293728 283224 293640 277349 299455 313917 298437 277410 282360 187762 113746 131224
[14:49:05.974] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:49:05.974] INFO: number of Vcal hits detected: 207746 207797 207525 207281 207241 199190 207271 207304 207292 207174 207145 207328 207366 207611 207780 207809
[14:49:05.974] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.6 99.7 99.7 99.7 99.6 99.6 99.7 99.7 99.8 99.9 99.9
[14:49:05.974] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.6 95.8 99.6 99.7 99.7 99.6 99.6 99.7 99.7 99.8 99.9 99.9
[14:49:05.974] INFO: X-ray hit rate [MHz/cm2]: 44.9 33.2 55.4 81.8 86.1 83.0 86.1 81.3 87.8 92.0 87.5 81.3 82.8 55.0 33.3 38.5
[14:49:05.974] INFO: PixTestHighRate::doXPixelAlive() done
[14:49:06.019] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:49:06.019] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:49:06.019] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:49:06.019] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:49:06.019] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:49:06.019] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:49:06.019] INFO: PixTest:: pg_setup set to default.
[14:49:10.121] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:49:10.121] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[14:49:11.248] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[14:49:11.249] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 383.5mA
[14:49:11.250] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[14:49:11.250] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[14:49:11.250] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[14:49:11.250] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[14:49:11.250] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[14:49:11.250] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[14:49:11.250] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[14:49:11.250] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[14:49:11.251] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[14:49:11.251] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[14:49:11.251] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[14:49:11.251] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:49:11.343] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[14:49:11.343] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:49:11.352] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[14:49:11.352] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[14:49:11.352] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[14:49:11.352] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[14:49:11.352] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[14:49:11.352] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:49:11.355] QUIET: Connection to board 43 closed.
[14:49:11.434] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[14:33:15.169] INFO: *** Welcome to pxar ***
[14:33:15.169] INFO: *** Today: 2016/06/13
[14:33:15.194] INFO: *** Version: v1.9.0-814-g7497
[14:33:15.194] INFO: readRocDacs: data/mc444/dacParameters35_C0.dat .. data/mc444/dacParameters35_C15.dat
[14:33:15.195] INFO: readTbmDacs: data/mc444/tbmParameters_C0a.dat .. data/mc444/tbmParameters_C0b.dat
[14:33:15.195] INFO: readMaskFile: data/mc444/defaultMaskFile.dat
[14:33:15.195] INFO: readTrimFile: data/mc444/trimParameters35_C0.dat .. data/mc444/trimParameters35_C15.dat
[14:33:15.345] INFO: clk: 4
[14:33:15.345] INFO: ctr: 4
[14:33:15.345] INFO: sda: 19
[14:33:15.345] INFO: tin: 9
[14:33:15.345] INFO: level: 15
[14:33:15.346] INFO: triggerdelay: 0
[14:33:15.346] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[14:33:15.346] INFO: Log level: DEBUG
[14:33:15.357] QUIET: Connection to board DTB_WRMNMR opened.
[14:33:15.360] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 43
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRMNMR
MAC address: 40D85511802B
Hostname: pixelDTB043
Comment: sent to Frank Meier (UNL)
------------------------------------------------------
[14:33:15.363] INFO: RPC call hashes of host and DTB match: 398089610
[14:33:16.964] INFO: DUT info:
[14:33:16.964] INFO: The DUT currently contains the following objects:
[14:33:16.964] INFO: 2 TBM Cores tbm08c (2 ON)
[14:33:16.964] INFO: TBM Core alpha (0): 7 registers set
[14:33:16.964] INFO: TBM Core beta (1): 7 registers set
[14:33:16.964] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:33:16.964] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.964] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.964] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.964] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.964] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.964] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.964] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.964] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.964] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.964] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.964] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.964] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.965] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.965] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.965] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.965] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:33:16.965] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:33:16.966] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:33:16.967] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:33:16.968] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:33:16.970] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31584256
[14:33:16.970] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x27260d0
[14:33:16.970] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x24c8360
[14:33:16.970] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f1875d94010
[14:33:16.970] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f187bfff510
[14:33:16.970] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31592448 fPxarMemory = 0x7f1875d94010
[14:33:16.972] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 380.2mA
[14:33:16.973] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[14:33:16.973] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[14:33:17.470] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2b10d70
[14:33:17.532] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:33:17.532] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:33:17.532] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:33:17.538] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:33:17.538] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:33:17.538] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:33:17.548] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:33:17.548] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:33:17.560] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:33:17.560] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:33:17.574] INFO: PixTestCmd::init()
[14:33:17.587] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:33:17.587] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:33:17.587] INFO: readGainPedestalParameters data/mc444/phCalibrationFitErr35_C0.dat .. data/mc444/phCalibrationFitErr35_C15.dat
[14:33:17.834] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:33:17.834] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:33:17.849] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:33:17.855] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:33:17.855] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:33:17.855] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:33:17.880] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:33:17.880] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:33:17.880] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:33:17.880] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:33:17.880] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:33:17.886] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:33:17.886] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:33:17.907] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:33:17.907] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:33:17.907] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:33:17.907] INFO: readReadbackCal: data/mc444/readbackCal_C0.dat .. data/mc444/readbackCal_C15.dat
[14:33:17.919] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:33:17.928] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[14:33:17.928] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[14:33:17.928] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[14:33:17.928] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[14:33:17.950] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:33:17.950] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:33:25.211] INFO: ######################################################################
[14:33:25.212] INFO: PixTestAlive::doTest()
[14:33:25.212] INFO: ######################################################################
[14:33:25.215] INFO: ----------------------------------------------------------------------
[14:33:25.215] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:33:25.215] INFO: ----------------------------------------------------------------------
[14:33:25.217] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:33:25.598] INFO: Expecting 41600 events.
[14:33:29.819] INFO: 41600 events read in total (3503ms).
[14:33:29.967] INFO: Test took 4750ms.
[14:33:29.980] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:29.980] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 65384
[14:33:29.980] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:33:30.262] INFO: PixTestAlive::aliveTest() done
[14:33:30.262] INFO: number of dead pixels (per ROC): 131 54 54 54 54 55 54 132 132 54 54 54 54 54 54 132
[14:33:30.262] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 165 86 95 96 102 107 116 182 171 122 90 99 100 87 91 153
[14:33:30.287] INFO: ----------------------------------------------------------------------
[14:33:30.287] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:33:30.287] INFO: ----------------------------------------------------------------------
[14:33:30.290] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:33:30.668] INFO: Expecting 41600 events.
[14:33:33.607] INFO: 41600 events read in total (2223ms).
[14:33:33.607] INFO: Test took 3317ms.
[14:33:33.607] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:33.607] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[14:33:33.607] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[14:33:33.608] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:33:34.030] INFO: PixTestAlive::maskTest() done
[14:33:34.030] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:33:34.053] INFO: ----------------------------------------------------------------------
[14:33:34.053] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:33:34.053] INFO: ----------------------------------------------------------------------
[14:33:34.055] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:33:34.434] INFO: Expecting 41600 events.
[14:33:38.634] INFO: 41600 events read in total (3485ms).
[14:33:38.635] INFO: Test took 4580ms.
[14:33:38.646] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:38.646] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 65385
[14:33:38.646] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[14:33:39.021] INFO: PixTestAlive::addressDecodingTest() done
[14:33:39.021] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:33:39.021] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[14:33:44.512] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[14:33:44.512] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:33:44.512] INFO: ----------------------------------------------------------------------
[14:33:44.512] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:33:44.512] INFO: ----------------------------------------------------------------------
[14:33:44.652] INFO: Expecting 768 events.
[14:33:45.781] INFO: 768 events read in total (414ms).
[14:33:45.781] INFO: Test took 1263ms.
[14:33:45.787] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:33:46.584] INFO: Expecting 41600 events.
[14:33:50.187] INFO: 41600 events read in total (3076ms).
[14:33:50.192] INFO: Test took 4405ms.
[14:33:50.341] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:50.341] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 854303
[14:33:50.341] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:33:50.341] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:33:50.406] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:33:51.073] INFO: Expecting 41600 events.
[14:33:54.865] INFO: 41600 events read in total (3265ms).
[14:33:54.870] INFO: Test took 4464ms.
[14:33:55.020] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:55.020] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 926849
[14:33:55.020] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:33:55.020] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:33:55.089] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:33:55.754] INFO: Expecting 41600 events.
[14:33:59.684] INFO: 41600 events read in total (3403ms).
[14:33:59.690] INFO: Test took 4601ms.
[14:33:59.852] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:33:59.852] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 979853
[14:33:59.852] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:33:59.853] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:33:59.923] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:34:00.588] INFO: Expecting 41600 events.
[14:34:04.585] INFO: 41600 events read in total (3470ms).
[14:34:04.592] INFO: Test took 4669ms.
[14:34:04.759] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:04.759] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1015650
[14:34:04.759] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:34:04.759] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:34:04.833] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:34:05.500] INFO: Expecting 41600 events.
[14:34:09.491] INFO: 41600 events read in total (3464ms).
[14:34:09.498] INFO: Test took 4664ms.
[14:34:09.668] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:09.669] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1035484
[14:34:09.669] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:34:09.669] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:34:09.745] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:34:10.410] INFO: Expecting 41600 events.
[14:34:14.457] INFO: 41600 events read in total (3521ms).
[14:34:14.464] INFO: Test took 4719ms.
[14:34:14.635] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:14.635] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1043213
[14:34:14.635] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:34:14.636] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:34:14.712] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:34:15.378] INFO: Expecting 41600 events.
[14:34:19.443] INFO: 41600 events read in total (3539ms).
[14:34:19.451] INFO: Test took 4739ms.
[14:34:19.638] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:19.638] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1053480
[14:34:19.638] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:34:19.638] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:34:19.713] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:34:20.378] INFO: Expecting 41600 events.
[14:34:24.456] INFO: 41600 events read in total (3551ms).
[14:34:24.462] INFO: Test took 4749ms.
[14:34:24.639] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:24.639] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1058997
[14:34:24.639] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:34:24.640] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:34:24.719] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:34:25.387] INFO: Expecting 41600 events.
[14:34:29.504] INFO: 41600 events read in total (3591ms).
[14:34:29.510] INFO: Test took 4791ms.
[14:34:29.686] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:29.686] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1063130
[14:34:29.686] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:34:29.686] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:34:29.765] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:34:30.429] INFO: Expecting 41600 events.
[14:34:34.529] INFO: 41600 events read in total (3573ms).
[14:34:34.535] INFO: Test took 4770ms.
[14:34:34.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:34.714] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1063901
[14:34:34.714] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:34:34.715] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:34:34.793] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:34:35.458] INFO: Expecting 41600 events.
[14:34:39.546] INFO: 41600 events read in total (3561ms).
[14:34:39.552] INFO: Test took 4759ms.
[14:34:39.731] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:39.731] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1066985
[14:34:39.731] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:34:39.731] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:34:39.810] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:34:40.475] INFO: Expecting 41600 events.
[14:34:44.568] INFO: 41600 events read in total (3566ms).
[14:34:44.574] INFO: Test took 4764ms.
[14:34:44.753] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:44.753] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1069539
[14:34:44.753] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:34:44.753] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:34:44.835] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:34:45.502] INFO: Expecting 41600 events.
[14:34:49.597] INFO: 41600 events read in total (3569ms).
[14:34:49.603] INFO: Test took 4768ms.
[14:34:49.783] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:49.783] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1070615
[14:34:49.783] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:34:49.784] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:34:49.865] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:34:50.532] INFO: Expecting 41600 events.
[14:34:54.619] INFO: 41600 events read in total (3560ms).
[14:34:54.625] INFO: Test took 4760ms.
[14:34:54.808] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:54.808] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1072487
[14:34:54.808] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:34:54.808] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:34:54.889] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:34:55.557] INFO: Expecting 41600 events.
[14:34:59.674] INFO: 41600 events read in total (3590ms).
[14:34:59.680] INFO: Test took 4791ms.
[14:34:59.859] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:34:59.859] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1072363
[14:34:59.859] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:34:59.859] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:34:59.939] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:35:00.605] INFO: Expecting 41600 events.
[14:35:04.726] INFO: 41600 events read in total (3595ms).
[14:35:04.732] INFO: Test took 4793ms.
[14:35:04.914] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:04.914] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1072460
[14:35:04.914] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:35:04.914] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:35:04.995] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:35:05.663] INFO: Expecting 41600 events.
[14:35:09.769] INFO: 41600 events read in total (3580ms).
[14:35:09.775] INFO: Test took 4779ms.
[14:35:09.953] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:09.953] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1073621
[14:35:09.953] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:35:09.953] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:35:10.036] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:35:10.711] INFO: Expecting 41600 events.
[14:35:14.792] INFO: 41600 events read in total (3554ms).
[14:35:14.798] INFO: Test took 4762ms.
[14:35:14.981] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:14.981] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1073112
[14:35:14.981] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:35:14.981] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:35:15.063] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:35:15.728] INFO: Expecting 41600 events.
[14:35:19.788] INFO: 41600 events read in total (3533ms).
[14:35:19.794] INFO: Test took 4731ms.
[14:35:19.979] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:19.979] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1072912
[14:35:19.979] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:35:19.980] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:35:20.059] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:35:20.726] INFO: Expecting 41600 events.
[14:35:24.703] INFO: 41600 events read in total (3450ms).
[14:35:24.709] INFO: Test took 4650ms.
[14:35:24.890] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:35:24.890] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1068753
[14:35:24.890] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:35:24.890] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:35:25.195] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 177 eff = 0.997308
[14:35:25.195] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 137 eff = 0.997933
[14:35:25.196] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 177 eff = 0.995673
[14:35:25.196] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 148 eff = 0.993077
[14:35:25.196] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 162 eff = 0.993029
[14:35:25.196] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 174 eff = 0.954183
[14:35:25.196] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 187 eff = 0.992284
[14:35:25.197] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 160 eff = 0.992861
[14:35:25.197] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 165 eff = 0.993077
[14:35:25.197] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 190 eff = 0.991755
[14:35:25.197] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 158 eff = 0.991683
[14:35:25.197] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 151 eff = 0.993293
[14:35:25.197] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 183 eff = 0.993245
[14:35:25.198] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 153 eff = 0.995865
[14:35:25.198] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 142 eff = 0.997933
[14:35:25.198] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 164 eff = 0.997788
[14:36:05.608] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[14:36:05.608] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:36:05.608] INFO: ----------------------------------------------------------------------
[14:36:05.608] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:36:05.608] INFO: ----------------------------------------------------------------------
[14:36:05.609] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:36:05.609] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:36:05.609] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:36:05.609] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:36:05.609] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:36:05.609] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:36:05.609] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:36:05.609] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:36:05.609] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:36:05.609] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:36:05.609] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:36:05.609] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:36:05.629] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:36:06.296] INFO: Expecting 208000 events.
[14:36:24.978] INFO: 208000 events read in total (18155ms).
[14:36:25.002] INFO: Test took 19373ms.
[14:36:25.952] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:25.952] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 5042695
[14:36:25.952] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:36:25.953] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:36:26.514] INFO: number of dead pixels (per ROC): 0 0 0 0 0 160 0 0 0 0 0 0 0 0 0 0
[14:36:26.514] INFO: number of red-efficiency pixels: 392 334 640 1013 1164 1261 1216 1038 1155 1280 1211 1017 1006 602 298 295
[14:36:26.514] INFO: number of X-ray hits detected: 201927 149114 247157 367563 384210 371194 384498 363033 392266 411801 395382 365022 371301 249249 149645 172933
[14:36:26.514] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:36:26.514] INFO: number of Vcal hits detected: 207586 207630 207201 206770 206558 198622 206428 206673 206596 206392 206461 206749 206773 207289 207675 207691
[14:36:26.514] INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.7 99.5 99.4 99.4 99.3 99.4 99.4 99.3 99.3 99.5 99.5 99.7 99.9 99.9
[14:36:26.514] INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.6 99.4 99.3 95.5 99.2 99.4 99.3 99.2 99.3 99.4 99.4 99.7 99.8 99.9
[14:36:26.514] INFO: X-ray hit rate [MHz/cm2]: 59.2 43.7 72.4 107.7 112.6 108.8 112.7 106.4 115.0 120.7 115.9 107.0 108.8 73.1 43.9 50.7
[14:36:26.514] INFO: PixTestHighRate::doXPixelAlive() done
[14:36:26.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:36:26.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:36:26.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:36:26.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:36:26.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:36:26.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:36:26.559] INFO: PixTest:: pg_setup set to default.
[14:36:38.816] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[14:36:38.816] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[14:36:47.920] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[14:36:47.921] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 383.5mA
[14:36:47.922] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[14:36:47.922] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[14:36:47.922] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[14:36:47.922] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[14:36:47.922] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[14:36:47.922] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[14:36:47.922] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[14:36:47.923] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[14:36:47.923] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[14:36:47.923] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[14:36:47.923] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[14:36:47.923] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:36:48.017] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[14:36:48.017] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:36:48.026] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[14:36:48.026] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[14:36:48.026] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[14:36:48.026] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[14:36:48.026] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[14:36:48.026] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:36:48.029] QUIET: Connection to board 43 closed.
[14:36:48.108] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-895-gd4c3d74 on branch master